builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0295 starttime: 1484786232.55 results: success (0) buildid: 20170118115626 builduid: 8f3ef63259ff4b5692b6ad145e069615 revision: 09088789a419102ba1febf0885094657dbb4b532 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.553584) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.554071) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.554354) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.586891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.587394) ========= 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.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-18 16:37:12-- 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% 132M=0s 2017-01-18 16:37:12 (132 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.738212) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:12.738525) ========= 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.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.019709 ========= master_lag: 0.27 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:13.027753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 16:37:13.028169) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 09088789a419102ba1febf0885094657dbb4b532 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 09088789a419102ba1febf0885094657dbb4b532 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-18 16:37:13,118 truncating revision to first 12 chars 2017-01-18 16:37:13,118 Setting DEBUG logging. 2017-01-18 16:37:13,118 attempt 1/10 2017-01-18 16:37:13,118 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/09088789a419?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 16:37:14,669 unpacking tar archive at: graphics-09088789a419/testing/mozharness/ program finished with exit code 0 elapsedTime=1.813182 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 16:37:14.918954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:14.919264) ========= script_repo_revision: 09088789a419102ba1febf0885094657dbb4b532 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:14.919671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:14.919953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 16:37:14.938732) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-18 16:37:14.939160) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:15 INFO - MultiFileLogger online at 20170118 16:37:15 in /builds/slave/test 16:37:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 16:37:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:37:15 INFO - {'append_to_log': False, 16:37:15 INFO - 'base_work_dir': '/builds/slave/test', 16:37:15 INFO - 'blob_upload_branch': 'Graphics', 16:37:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:37:15 INFO - 'branch': 'Graphics', 16:37:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:37:15 INFO - 'config_files': ('talos/mac_config.py',), 16:37:15 INFO - 'default_actions': ('clobber', 16:37:15 INFO - 'read-buildbot-config', 16:37:15 INFO - 'download-and-extract', 16:37:15 INFO - 'populate-webroot', 16:37:15 INFO - 'create-virtualenv', 16:37:15 INFO - 'install', 16:37:15 INFO - 'run-tests'), 16:37:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:37:15 INFO - 'download_minidump_stackwalk': True, 16:37:15 INFO - 'download_symbols': 'ondemand', 16:37:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:37:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:37:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:37:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:37:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:37:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:37:15 INFO - 'installer_path': 'installer.exe', 16:37:15 INFO - 'log_level': 'info', 16:37:15 INFO - 'log_name': 'talos', 16:37:15 INFO - 'log_to_console': True, 16:37:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:37:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:37:15 INFO - 'opt_config_files': (), 16:37:15 INFO - 'pip_index': False, 16:37:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:37:15 INFO - 'cmd': ('bash', 16:37:15 INFO - '-c', 16:37:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:37:15 INFO - 'enabled': True, 16:37:15 INFO - 'halt_on_failure': False, 16:37:15 INFO - 'name': 'check_screen_resolution'},), 16:37:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:37:15 INFO - 'cmd': ('bash', 16:37:15 INFO - '-c', 16:37:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:37:15 INFO - 'enabled': True, 16:37:15 INFO - 'halt_on_failure': False, 16:37:15 INFO - 'name': 'check_screen_resolution'},), 16:37:15 INFO - 'run_cmd_checks_enabled': True, 16:37:15 INFO - 'sps_profile': False, 16:37:15 INFO - 'sps_profile_interval': 0, 16:37:15 INFO - 'suite': 'dromaeojs-e10s', 16:37:15 INFO - 'system_bits': '32', 16:37:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:37:15 INFO - 'title': 't-yosemite-r7-0295', 16:37:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:37:15 INFO - 'use_talos_json': True, 16:37:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:37:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:37:15 INFO - 'work_dir': 'build'} 16:37:15 INFO - [mozharness: 2017-01-19 00:37:15.143723Z] Running clobber step. 16:37:15 INFO - Running pre-action listener: _resource_record_pre_action 16:37:15 INFO - Running main action method: clobber 16:37:15 INFO - rmtree: /builds/slave/test/build 16:37:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:37:16 INFO - Running post-action listener: _resource_record_post_action 16:37:16 INFO - [mozharness: 2017-01-19 00:37:16.609593Z] Finished clobber step (success) 16:37:16 INFO - [mozharness: 2017-01-19 00:37:16.609764Z] Running read-buildbot-config step. 16:37:16 INFO - Running pre-action listener: _resource_record_pre_action 16:37:16 INFO - Running main action method: read_buildbot_config 16:37:16 INFO - Using buildbot properties: 16:37:16 INFO - { 16:37:16 INFO - "project": "", 16:37:16 INFO - "product": "firefox", 16:37:16 INFO - "script_repo_revision": "default", 16:37:16 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 16:37:16 INFO - "repository": "", 16:37:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 16:37:16 INFO - "buildid": "20170118115626", 16:37:16 INFO - "pgo_build": "False", 16:37:16 INFO - "basedir": "/builds/slave/test", 16:37:16 INFO - "buildnumber": 5, 16:37:16 INFO - "slavename": "t-yosemite-r7-0295", 16:37:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:37:16 INFO - "platform": "yosemite_r7", 16:37:16 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 16:37:16 INFO - "branch": "graphics", 16:37:16 INFO - "repo_path": "projects/graphics", 16:37:16 INFO - "revision": "09088789a419102ba1febf0885094657dbb4b532", 16:37:16 INFO - "stage_platform": "macosx64", 16:37:16 INFO - "builduid": "8f3ef63259ff4b5692b6ad145e069615", 16:37:16 INFO - "slavebuilddir": "test" 16:37:16 INFO - } 16:37:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:37:16 INFO - Running post-action listener: _resource_record_post_action 16:37:16 INFO - [mozharness: 2017-01-19 00:37:16.613028Z] Finished read-buildbot-config step (success) 16:37:16 INFO - [mozharness: 2017-01-19 00:37:16.613135Z] Running download-and-extract step. 16:37:16 INFO - Running pre-action listener: _resource_record_pre_action 16:37:16 INFO - Running main action method: download_and_extract 16:37:16 INFO - mkdir: /builds/slave/test/build/tests 16:37:16 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')]} 16:37:16 INFO - trying https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:37:16 INFO - Downloading https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/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 16:37:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/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 16:37:17 INFO - Downloaded 1199 bytes. 16:37:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:37:17 INFO - Using the following test package requirements: 16:37:17 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:37:17 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:37:17 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:37:17 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'jsshell-mac.zip'], 16:37:17 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:37:17 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:37:17 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:37:17 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:37:17 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:37:17 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:37:17 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:37:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:37:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:37:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:17 INFO - Fetch https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:37:18 INFO - Expected file size: 16328335 16:37:18 INFO - Obtained file size: 16328335 16:37:25 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 16:37:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:37:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:25 INFO - Fetch https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:37:26 INFO - Expected file size: 16328335 16:37:26 INFO - Obtained file size: 16328335 16:37:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:37:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:37:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:37:34 INFO - Expected file size: 14403463 16:37:34 INFO - Obtained file size: 14403463 16:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:34 INFO - trying https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/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 16:37:36 INFO - Downloaded 70019055 bytes. 16:37:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:37:36 INFO - mkdir: /builds/slave/test/properties 16:37:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:37:36 INFO - Writing to file /builds/slave/test/properties/build_url 16:37:36 INFO - Contents: 16:37:36 INFO - build_url:https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:37:37 INFO - Running post-action listener: _resource_record_post_action 16:37:37 INFO - Running post-action listener: set_extra_try_arguments 16:37:37 INFO - [mozharness: 2017-01-19 00:37:37.260660Z] Finished download-and-extract step (success) 16:37:37 INFO - [mozharness: 2017-01-19 00:37:37.260777Z] Running populate-webroot step. 16:37:37 INFO - Running pre-action listener: _resource_record_pre_action 16:37:37 INFO - Running main action method: populate_webroot 16:37:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:37:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:37:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:37:37 INFO - '--tppagecycles', 16:37:37 INFO - '7'], 16:37:37 INFO - 'tests': ['tsvgm']}}, 16:37:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:37:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:37:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:37:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:37:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['damp', 'tps']}, 16:37:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'tests': ['damp', 'tps']}, 16:37:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['dromaeo_dom']}, 16:37:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:37:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['basic_compositor_video']}, 16:37:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:37:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'other-e10s': {'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['a11yr', 16:37:37 INFO - 'ts_paint', 16:37:37 INFO - 'tpaint', 16:37:37 INFO - 'sessionrestore', 16:37:37 INFO - 'sessionrestore_no_auto_restore', 16:37:37 INFO - 'tabpaint']}, 16:37:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['tsvgx', 16:37:37 INFO - 'tsvgr_opacity', 16:37:37 INFO - 'tart', 16:37:37 INFO - 'tscrollx', 16:37:37 INFO - 'cart', 16:37:37 INFO - 'tsvg_static']}, 16:37:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:37:37 INFO - 'tsvgr_opacity', 16:37:37 INFO - 'tart', 16:37:37 INFO - 'tscrollx', 16:37:37 INFO - 'cart', 16:37:37 INFO - 'tsvg_static']}, 16:37:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'talos_options': ['--disable-e10s'], 16:37:37 INFO - 'tests': ['tp5o']}, 16:37:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:37:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'talos_options': ['--disable-e10s', 16:37:37 INFO - '--xperf_path', 16:37:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:37 INFO - 'tests': ['tp5n']}, 16:37:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:37:37 INFO - 'talos_options': ['--xperf_path', 16:37:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:37 INFO - 'tests': ['tp5n']}}, 16:37:37 INFO - 'talos.zip': {'path': '', 16:37:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:37:37 INFO - Running post-action listener: _resource_record_post_action 16:37:37 INFO - [mozharness: 2017-01-19 00:37:37.279054Z] Finished populate-webroot step (success) 16:37:37 INFO - [mozharness: 2017-01-19 00:37:37.279178Z] Running create-virtualenv step. 16:37:37 INFO - Running pre-action listener: _resource_record_pre_action 16:37:37 INFO - Running main action method: create_virtualenv 16:37:37 INFO - Creating virtualenv /builds/slave/test/build/venv 16:37:37 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 16:37:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:37:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:37:37 INFO - Using real prefix '/tools/python27' 16:37:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:37:38 INFO - Installing distribute.............................................................................................................................................................................................done. 16:37:40 INFO - Installing pip.................done. 16:37:40 INFO - Return code: 0 16:37:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:37:41 INFO - Reading from file tmpfile_stdout 16:37:41 INFO - Output received: 16:37:41 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) 16:37:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:41 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:41 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 16:37:41 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 16:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:41 INFO - 'HOME': '/Users/cltbld', 16:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:41 INFO - 'LOGNAME': 'cltbld', 16:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:41 INFO - 'MOZ_NO_REMOTE': '1', 16:37:41 INFO - 'NO_EM_RESTART': '1', 16:37:41 INFO - 'PAGER': '/bin/cat', 16:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:41 INFO - 'PWD': '/builds/slave/test', 16:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:41 INFO - 'SHELL': '/bin/bash', 16:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:41 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:41 INFO - 'USER': 'cltbld', 16:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:41 INFO - 'XPC_FLAGS': '0x0', 16:37:41 INFO - 'XPC_SERVICE_NAME': '0', 16:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:41 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 16:37:41 INFO - Cleaning up... 16:37:41 INFO - Return code: 0 16:37:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:41 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:41 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 16:37:41 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 16:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:41 INFO - 'HOME': '/Users/cltbld', 16:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:41 INFO - 'LOGNAME': 'cltbld', 16:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:41 INFO - 'MOZ_NO_REMOTE': '1', 16:37:41 INFO - 'NO_EM_RESTART': '1', 16:37:41 INFO - 'PAGER': '/bin/cat', 16:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:41 INFO - 'PWD': '/builds/slave/test', 16:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:41 INFO - 'SHELL': '/bin/bash', 16:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:41 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:41 INFO - 'USER': 'cltbld', 16:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:41 INFO - 'XPC_FLAGS': '0x0', 16:37:41 INFO - 'XPC_SERVICE_NAME': '0', 16:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:41 INFO - Downloading/unpacking psutil>=3.1.1 16:37:41 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 16:37:41 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 16:37:41 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 16:37:41 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 16:37:41 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 16:37:41 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 16:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:37:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:45 INFO - Installing collected packages: psutil 16:37:45 INFO - Running setup.py install for psutil 16:37:45 INFO - building 'psutil._psutil_osx' extension 16:37:45 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 16:37:45 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 16:37:45 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 16:37:45 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 16:37:45 INFO - building 'psutil._psutil_posix' extension 16:37:45 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 16:37:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:37:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:37:45 INFO - ^ 16:37:45 INFO - 1 warning generated. 16:37:45 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 16:37:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:46 INFO - Successfully installed psutil 16:37:46 INFO - Cleaning up... 16:37:46 INFO - Return code: 0 16:37:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:37: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 mozsystemmonitor==0.3 16:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:46 INFO - 'HOME': '/Users/cltbld', 16:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:46 INFO - 'LOGNAME': 'cltbld', 16:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:46 INFO - 'MOZ_NO_REMOTE': '1', 16:37:46 INFO - 'NO_EM_RESTART': '1', 16:37:46 INFO - 'PAGER': '/bin/cat', 16:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:46 INFO - 'PWD': '/builds/slave/test', 16:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:46 INFO - 'SHELL': '/bin/bash', 16:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:46 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:46 INFO - 'USER': 'cltbld', 16:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:46 INFO - 'XPC_FLAGS': '0x0', 16:37:46 INFO - 'XPC_SERVICE_NAME': '0', 16:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:37:49 INFO - Installing collected packages: mozsystemmonitor 16:37:49 INFO - Running setup.py install for mozsystemmonitor 16:37:49 INFO - Successfully installed mozsystemmonitor 16:37:49 INFO - Cleaning up... 16:37:49 INFO - Return code: 0 16:37:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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', '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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:37: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 jsonschema==2.5.1 16:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:49 INFO - 'HOME': '/Users/cltbld', 16:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:49 INFO - 'LOGNAME': 'cltbld', 16:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:49 INFO - 'MOZ_NO_REMOTE': '1', 16:37:49 INFO - 'NO_EM_RESTART': '1', 16:37:49 INFO - 'PAGER': '/bin/cat', 16:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:49 INFO - 'PWD': '/builds/slave/test', 16:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:49 INFO - 'SHELL': '/bin/bash', 16:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:49 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:49 INFO - 'USER': 'cltbld', 16:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:49 INFO - 'XPC_FLAGS': '0x0', 16:37:49 INFO - 'XPC_SERVICE_NAME': '0', 16:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:49 INFO - Downloading/unpacking jsonschema==2.5.1 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:37:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:37: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 16:37: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 16:37:52 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 16:37:52 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 16:37: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 16:37: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 16:37:52 INFO - Downloading functools32-3.2.3-2.tar.gz 16:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:37:52 INFO - warning: no files found matching '*.txt' 16:37:52 INFO - no previously-included directories found matching 'build' 16:37:52 INFO - no previously-included directories found matching 'dist' 16:37:52 INFO - no previously-included directories found matching '.git*' 16:37:52 INFO - Installing collected packages: jsonschema, functools32 16:37:52 INFO - Running setup.py install for functools32 16:37:52 INFO - warning: no files found matching '*.txt' 16:37:52 INFO - no previously-included directories found matching 'build' 16:37:52 INFO - no previously-included directories found matching 'dist' 16:37:52 INFO - no previously-included directories found matching '.git*' 16:37:52 INFO - Successfully installed jsonschema functools32 16:37:52 INFO - Cleaning up... 16:37:52 INFO - Return code: 0 16:37:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:37:52 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')]} 16:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:52 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')]} 16:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:52 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:52 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 16:37:52 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 16:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:52 INFO - 'HOME': '/Users/cltbld', 16:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:52 INFO - 'LOGNAME': 'cltbld', 16:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:52 INFO - 'MOZ_NO_REMOTE': '1', 16:37:52 INFO - 'NO_EM_RESTART': '1', 16:37:52 INFO - 'PAGER': '/bin/cat', 16:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:52 INFO - 'PWD': '/builds/slave/test', 16:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:52 INFO - 'SHELL': '/bin/bash', 16:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:52 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:52 INFO - 'USER': 'cltbld', 16:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:52 INFO - 'XPC_FLAGS': '0x0', 16:37:52 INFO - 'XPC_SERVICE_NAME': '0', 16:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:37:53 INFO - Cleaning up... 16:37:53 INFO - Return code: 0 16:37:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:37:53 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')]} 16:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 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')]} 16:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:53 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 16:37:53 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 16:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:53 INFO - 'HOME': '/Users/cltbld', 16:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:53 INFO - 'LOGNAME': 'cltbld', 16:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:53 INFO - 'MOZ_NO_REMOTE': '1', 16:37:53 INFO - 'NO_EM_RESTART': '1', 16:37:53 INFO - 'PAGER': '/bin/cat', 16:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:53 INFO - 'PWD': '/builds/slave/test', 16:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:53 INFO - 'SHELL': '/bin/bash', 16:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:53 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:53 INFO - 'USER': 'cltbld', 16:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:53 INFO - 'XPC_FLAGS': '0x0', 16:37:53 INFO - 'XPC_SERVICE_NAME': '0', 16:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37: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 16:37:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:37:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:37:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:56 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 16:37:57 INFO - Downloading docopt-0.6.1.tar.gz 16:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:37:57 INFO - Installing collected packages: blobuploader, requests, docopt 16:37:57 INFO - Running setup.py install for blobuploader 16:37:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:37:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:37:57 INFO - Running setup.py install for requests 16:37:58 INFO - Running setup.py install for docopt 16:37:58 INFO - Successfully installed blobuploader requests docopt 16:37:58 INFO - Cleaning up... 16:37:58 INFO - Return code: 0 16:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:58 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')]} 16:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:58 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')]} 16:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:58 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:37:58 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 16:37:58 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 16:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:58 INFO - 'HOME': '/Users/cltbld', 16:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:58 INFO - 'LOGNAME': 'cltbld', 16:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:58 INFO - 'MOZ_NO_REMOTE': '1', 16:37:58 INFO - 'NO_EM_RESTART': '1', 16:37:58 INFO - 'PAGER': '/bin/cat', 16:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:58 INFO - 'PWD': '/builds/slave/test', 16:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:58 INFO - 'SHELL': '/bin/bash', 16:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:37:58 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:58 INFO - 'USER': 'cltbld', 16:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:58 INFO - 'XPC_FLAGS': '0x0', 16:37:58 INFO - 'XPC_SERVICE_NAME': '0', 16:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:58 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-OxADoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:58 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-smm0LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:58 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-0gIrzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:58 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ea0rhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:58 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-wX3R8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-knf1jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-KQ7J2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-LjzCGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-aGD0vR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-gjrXTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-0ggqnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-pvjlI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-DnCor2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Ss1Vtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:59 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-CLo872-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-yDC2si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-PxZOsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:38:00 INFO - Running setup.py install for manifestparser 16:38:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:38:00 INFO - Running setup.py install for mozcrash 16:38:00 INFO - Running setup.py install for mozdebug 16:38:00 INFO - Running setup.py install for mozdevice 16:38:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:38:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:38:00 INFO - Running setup.py install for mozfile 16:38:01 INFO - Running setup.py install for mozhttpd 16:38:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Running setup.py install for mozinfo 16:38:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Running setup.py install for mozInstall 16:38:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Running setup.py install for mozleak 16:38:01 INFO - Running setup.py install for mozlog 16:38:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Running setup.py install for moznetwork 16:38:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Running setup.py install for mozprocess 16:38:02 INFO - Running setup.py install for mozprofile 16:38:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:38:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:38:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:38:02 INFO - Running setup.py install for mozrunner 16:38:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:38:02 INFO - Running setup.py install for mozscreenshot 16:38:02 INFO - Running setup.py install for moztest 16:38:02 INFO - Running setup.py install for mozversion 16:38:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:38:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:38:02 INFO - Cleaning up... 16:38:02 INFO - Return code: 0 16:38:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:02 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:38:02 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 16:38:02 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 16:38:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:38:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:02 INFO - 'HOME': '/Users/cltbld', 16:38:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:02 INFO - 'LOGNAME': 'cltbld', 16:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:02 INFO - 'MOZ_NO_REMOTE': '1', 16:38:02 INFO - 'NO_EM_RESTART': '1', 16:38:02 INFO - 'PAGER': '/bin/cat', 16:38:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:02 INFO - 'PWD': '/builds/slave/test', 16:38:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:02 INFO - 'SHELL': '/bin/bash', 16:38:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:38:02 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:38:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:02 INFO - 'USER': 'cltbld', 16:38:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:02 INFO - 'XPC_FLAGS': '0x0', 16:38:02 INFO - 'XPC_SERVICE_NAME': '0', 16:38:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ZERydO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-TNHzcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-wVBcZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-L3BOjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-fJQX1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-hIvAe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-gQf8qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:38:03 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)) 16:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:38:03 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Q31UUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Qtec_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-M3KA5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-AJlfn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-DsZrd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-PQk1Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-RQMjYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-aG8X5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-wfGzof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:04 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)) 16:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:04 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-vSlG3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:04 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)) 16:38:04 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)) 16:38:04 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)) 16:38:04 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)) 16:38:04 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)) 16:38:04 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)) 16:38:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:38:04 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 16:38:04 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 16:38:04 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 16:38:04 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 16:38:04 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 16:38:04 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 16:38:07 INFO - Downloading blessings-1.6.tar.gz 16:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:38:07 INFO - Installing collected packages: blessings 16:38:07 INFO - Running setup.py install for blessings 16:38:07 INFO - Successfully installed blessings 16:38:07 INFO - Cleaning up... 16:38:07 INFO - Return code: 0 16:38:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:08 INFO - Reading from file tmpfile_stdout 16:38:08 INFO - Current package versions: 16:38:08 INFO - blessings == 1.6 16:38:08 INFO - blobuploader == 1.2.4 16:38:08 INFO - docopt == 0.6.1 16:38:08 INFO - functools32 == 3.2.3-2 16:38:08 INFO - jsonschema == 2.5.1 16:38:08 INFO - manifestparser == 1.1 16:38:08 INFO - mozInstall == 1.12 16:38:08 INFO - mozcrash == 1.0 16:38:08 INFO - mozdebug == 0.1 16:38:08 INFO - mozdevice == 0.49 16:38:08 INFO - mozfile == 1.2 16:38:08 INFO - mozhttpd == 0.7 16:38:08 INFO - mozinfo == 0.9 16:38:08 INFO - mozleak == 0.1 16:38:08 INFO - mozlog == 3.4 16:38:08 INFO - moznetwork == 0.27 16:38:08 INFO - mozprocess == 0.24 16:38:08 INFO - mozprofile == 0.28 16:38:08 INFO - mozrunner == 6.13 16:38:08 INFO - mozscreenshot == 0.1 16:38:08 INFO - mozsystemmonitor == 0.3 16:38:08 INFO - moztest == 0.8 16:38:08 INFO - mozversion == 1.4 16:38:08 INFO - psutil == 3.1.1 16:38:08 INFO - requests == 1.2.3 16:38:08 INFO - wsgiref == 0.1.2 16:38:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:08 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')]} 16:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:08 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')]} 16:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:09 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:38:09 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 16:38:09 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 16:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:09 INFO - 'HOME': '/Users/cltbld', 16:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:09 INFO - 'LOGNAME': 'cltbld', 16:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:09 INFO - 'MOZ_NO_REMOTE': '1', 16:38:09 INFO - 'NO_EM_RESTART': '1', 16:38:09 INFO - 'PAGER': '/bin/cat', 16:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:09 INFO - 'PWD': '/builds/slave/test', 16:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:09 INFO - 'SHELL': '/bin/bash', 16:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:38:09 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:09 INFO - 'USER': 'cltbld', 16:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:09 INFO - 'XPC_FLAGS': '0x0', 16:38:09 INFO - 'XPC_SERVICE_NAME': '0', 16:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 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)) 16:38:09 INFO - Cleaning up... 16:38:09 INFO - Return code: 0 16:38:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:38:09 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')]} 16:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:09 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')]} 16:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:09 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 0x102ef5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb51495800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, '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.BHYDI3UEno/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.6etqApeny6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 16:38:09 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 16:38:09 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 16:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:09 INFO - 'HOME': '/Users/cltbld', 16:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:09 INFO - 'LOGNAME': 'cltbld', 16:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:09 INFO - 'MOZ_NO_REMOTE': '1', 16:38:09 INFO - 'NO_EM_RESTART': '1', 16:38:09 INFO - 'PAGER': '/bin/cat', 16:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:09 INFO - 'PWD': '/builds/slave/test', 16:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:09 INFO - 'SHELL': '/bin/bash', 16:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:38:09 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:09 INFO - 'USER': 'cltbld', 16:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:09 INFO - 'XPC_FLAGS': '0x0', 16:38:09 INFO - 'XPC_SERVICE_NAME': '0', 16:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:38:10 INFO - Cleaning up... 16:38:10 INFO - Return code: 0 16:38:10 INFO - Running post-action listener: _resource_record_post_action 16:38:10 INFO - Running post-action listener: _start_resource_monitoring 16:38:10 INFO - Starting resource monitoring. 16:38:10 INFO - [mozharness: 2017-01-19 00:38:10.193013Z] Finished create-virtualenv step (success) 16:38:10 INFO - [mozharness: 2017-01-19 00:38:10.194035Z] Running install step. 16:38:10 INFO - Running pre-action listener: _resource_record_pre_action 16:38:10 INFO - Running main action method: install 16:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:10 INFO - Reading from file tmpfile_stdout 16:38:10 INFO - Detecting whether we're running mozinstall >=1.0... 16:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:38:10 INFO - Reading from file tmpfile_stdout 16:38:10 INFO - Output received: 16:38:10 INFO - Usage: mozinstall [options] installer 16:38:10 INFO - Options: 16:38:10 INFO - -h, --help show this help message and exit 16:38:10 INFO - -d DEST, --destination=DEST 16:38:10 INFO - Directory to install application into. [default: 16:38:10 INFO - "/builds/slave/test"] 16:38:10 INFO - --app=APP Application being installed. [default: firefox] 16:38:10 INFO - mkdir: /builds/slave/test/build/application 16:38:10 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'] 16:38:10 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 16:38:32 INFO - Reading from file tmpfile_stdout 16:38:32 INFO - Output received: 16:38:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:38:32 INFO - Running post-action listener: _resource_record_post_action 16:38:32 INFO - [mozharness: 2017-01-19 00:38:32.648369Z] Finished install step (success) 16:38:32 INFO - [mozharness: 2017-01-19 00:38:32.648535Z] Running run-tests step. 16:38:32 INFO - Running pre-action listener: _resource_record_pre_action 16:38:32 INFO - Running main action method: run_tests 16:38:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:32 INFO - 2017-01-18 16:38:32.663 screenresolution[1898:20770] starting screenresolution argv=screenresolution get 16:38:32 INFO - 2017-01-18 16:38:32.665 screenresolution[1898:20770] Display 0: 1600x1200x32@60 16:38:32 INFO - 2017-01-18 16:38:32.671 screenresolution[1899:20773] starting screenresolution argv=screenresolution list 16:38:32 INFO - Available Modes on Display 0 16:38:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:32 INFO - Intel Iris: 16:38:32 INFO - Chipset Model: Intel Iris 16:38:32 INFO - Type: GPU 16:38:32 INFO - Bus: Built-In 16:38:32 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:32 INFO - Vendor: Intel (0x8086) 16:38:32 INFO - Device ID: 0x0a2e 16:38:32 INFO - Revision ID: 0x0009 16:38:32 INFO - Displays: 16:38:32 INFO - Display: 16:38:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:32 INFO - Display Serial Number: 1600x1200 60 16:38:32 INFO - Main Display: Yes 16:38:32 INFO - Mirror: Off 16:38:32 INFO - Online: Yes 16:38:32 INFO - Rotation: Supported 16:38:32 INFO - Return code: 0 16:38:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:38:32 INFO - Python 2.7.3 16:38:32 INFO - Return code: 0 16:38:32 INFO - grabbing minidump binary from tooltool 16:38:32 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')]} 16:38:32 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 0x102cd3d30>, '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 0x102bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cc8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:38:32 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 16:38:32 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 16:38:32 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 16:38:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:38:33 INFO - Return code: 0 16:38:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:38:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:38:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:38:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:38:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0295 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:38:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BHYDI3UEno/Render', 16:38:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:33 INFO - 'HOME': '/Users/cltbld', 16:38:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:33 INFO - 'LOGNAME': 'cltbld', 16:38:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:38:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:33 INFO - 'MOZ_NO_REMOTE': '1', 16:38:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:38:33 INFO - 'NO_EM_RESTART': '1', 16:38:33 INFO - 'PAGER': '/bin/cat', 16:38:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:33 INFO - 'PWD': '/builds/slave/test', 16:38:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:38:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:33 INFO - 'SHELL': '/bin/bash', 16:38:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6etqApeny6/Listeners', 16:38:33 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 16:38:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:33 INFO - 'USER': 'cltbld', 16:38:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:33 INFO - 'XPC_FLAGS': '0x0', 16:38:33 INFO - 'XPC_SERVICE_NAME': '0', 16:38:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:38:33 INFO - mozversion application_buildid: 20170118115626 16:38:33 INFO - mozversion application_changeset: 09088789a419102ba1febf0885094657dbb4b532 16:38:33 INFO - mozversion application_display_name: Nightly 16:38:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:38:33 INFO - mozversion application_name: Firefox 16:38:33 INFO - mozversion application_remotingname: firefox 16:38:33 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:38:33 INFO - mozversion application_vendor: Mozilla 16:38:33 INFO - mozversion application_version: 53.0a1 16:38:33 INFO - mozversion platform_buildid: 20170118115626 16:38:33 INFO - mozversion platform_changeset: 09088789a419102ba1febf0885094657dbb4b532 16:38:33 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:38:33 INFO - mozversion platform_version: 53.0a1 16:38:33 INFO - using testdate: 1484786313 16:38:33 INFO - actual date: 1484786313 16:38:33 INFO - starting webserver on 'localhost:56179' 16:38:33 INFO - SUITE-START | Running 2 tests 16:38:33 INFO - TEST-START | dromaeo_css 16:38:33 INFO - operating with platform_type : mac_ 16:38:33 INFO - Initialising browser for dromaeo_css test... 16:38:33 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpezH9R2/profile http://localhost:56179/getInfo.html) 16:38:36 INFO - PROCESS | 1909 | 2017-01-18 16:38:36.173 plugin-container[1911:20933] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 16:38:36 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:36 INFO - PROCESS | 1909 | 2017-01-18 16:38:36.176 plugin-container[1911:20933] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 16:38:36 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:36 INFO - PROCESS | 1909 | 2017-01-18 16:38:36.177 plugin-container[1911:20933] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:38:36 INFO - PROCESS | 1909 | __metrics Screen width/height:1600/1200 16:38:36 INFO - PROCESS | 1909 | colorDepth:24 16:38:36 INFO - PROCESS | 1909 | Browser inner width/height: 1024/689 16:38:36 INFO - PROCESS | 1909 | __metrics 16:38:36 ERROR - PROCESS | 1909 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 16:38:36 INFO - TEST-INFO | 1909: exit 0 16:38:36 INFO - Browser initialized. 16:38:36 INFO - Running cycle 1/1 for dromaeo_css test... 16:38:36 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpezH9R2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:39 INFO - PROCESS | 1912 | 2017-01-18 16:38:39.346 plugin-container[1913:21048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8633, name = 'com.apple.tsm.portname' 16:38:39 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:39 INFO - PROCESS | 1912 | 2017-01-18 16:38:39.347 plugin-container[1913:21048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:38:39 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:38:39 INFO - PROCESS | 1912 | 2017-01-18 16:38:39.347 plugin-container[1913:21048] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:38:49 INFO - PROCESS | 1912 | RSS: Main: 231620608 16:38:49 INFO - PROCESS | 1912 | 16:41:51 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56179/tests/dromaeo/cssquery-ext.html) 16:41:53 INFO - PROCESS | 1912 | RSS: Main: 249470976 16:41:53 INFO - PROCESS | 1912 | 16:43:37 INFO - PROCESS | 1912 | ************************* 16:43:37 INFO - PROCESS | 1912 | A coding exception was thrown and uncaught in a Task. 16:43:37 INFO - PROCESS | 1912 | 16:43:37 ERROR - PROCESS | 1912 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:43:37 INFO - PROCESS | 1912 | Full stack: 16:43:37 INFO - PROCESS | 1912 | ************************* 16:44:55 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-ext.html (next: http://localhost:56179/tests/dromaeo/cssquery-jquery.html) 16:44:56 INFO - PROCESS | 1912 | RSS: Main: 260911104 16:44:56 INFO - PROCESS | 1912 | 16:47:58 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56179/tests/dromaeo/cssquery-mootools.html) 16:47:58 INFO - PROCESS | 1912 | RSS: Main: 269066240 16:47:58 INFO - PROCESS | 1912 | 16:51:00 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56179/tests/dromaeo/cssquery-prototype.html) 16:51:01 INFO - PROCESS | 1912 | RSS: Main: 265289728 16:51:01 INFO - PROCESS | 1912 | 16:54:04 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56179/tests/dromaeo/cssquery-yui.html) 16:54:04 INFO - PROCESS | 1912 | RSS: Main: 270389248 16:54:04 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:56179/tests/dromaeo/cssquery-yui.html (next: http://localhost:56179/tests/dromaeo/cssquery-dojo.html) 16:57:07 INFO - PROCESS | 1912 | RSS: Main: 272814080 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | __start_tp_report 16:57:07 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 16:57:07 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 16:57:07 INFO - PROCESS | 1912 | |i|pagename|runs| 16:57:07 INFO - PROCESS | 1912 | |0;dojo.html;2675.7591816736494;3337.9819155157666;3364.5794275715534;3386.2719515304207;3427.828608569571;4450.310201918704;5671.8014869479575;5706.572007099463;5718.228039214705;5792.855178620536;4854.660173787836;5472.671639701621;5581.079121944874;5687.943120568793;5770.480656740903;4253.447051883266;4351.325544540586;5229.581633469333;5351.518363347298;5361.0459802090345;5181.1969144782615;5340.866478338054;5356.625036247464;5404.891902161954;5470.343558772953;4999.600031997468;5022.748862556894;5315.415304316542;5330.28041214435;5332.643795903527;5100.744962751847;5120.974395128038;5123.641345105844;5203.297554830087;5252.080885844962;5222.738863056832;5239.13554263546;5298.761555730001;5316.680999140025;5326.307580014612;5623.634463759842;5786.88426231473;5802.622829516068;5868.471837534642;5881.85295367615;6112.5946701277735;6164.6917654117115;6230.781922632731;6243.781467648656;6244.907141250308;6105.914670190231;6110.771402862501;6155.784547540815;6174.228221472316;6215.689215539205;5768.048272035151;5814.592978491507;5832.504237139806;5840.153177789197;5846.590738648296;6146.0473626587955;6201.503879689614;6252;6277.843053923689;6282.905756413657;6118.4059889896;6123.313124872426;6137.570370074052;6148.200733904653;6239.095331176954;6248.31268560458;6248.31268560458;6248.812535623939;6250.249970003629;6282.623042617458;6116.731440059295;6122.245686735438;6160.10678451631;6170.81487555374;6203.193584833943;6131.325554189036;6146.692665366714;6178.598391104564;6217.533684973645;6253.37466253371;5044.621653376012;5056.494350564914;5060.696358218519;5062.443131255559;5104.897902041938;4503.684742068024;4512;4518.819247230074;4537.0925814837165;4537.77311134442;5000.699958002531;5791.449812267828;5815.88368232633;5842.269716285465;5928.377520360386;6127.617148554337;6184.257889053342;6198.2252218472695;6227.221597300338;6267.655278959701;5981.700914954235;5981.790637327673;5992.121911431232;6007.759689612454;6020.187274717857;5517.172424136332;5520.751566179531;5532.6680399176175;5549.6115271931385;5561.110222364402;6216.595921265194;6239.064140378889;6370.713317900613;6381.87236255263;6401.935980640134;5272.604554658493;5711.685857277889;5741.368449470471;6229.065640153832;6265.342139075331;5267.920981185362;5359.651622644672;6390.297067322684;6468.514861385321;6479.060536222127;6085.4523092921845;6112.1387513176505;6118.388161183846;6149.72326245329;6233;5133.922991155061;6160.168377269011;6206.906896396467;6217.098520714561;6229.408206220402;5863.384344643751;5928.911066334094;6053.334133245251;6063.090536419572;6091.7563297467605;5692.089265717465;5995.730192141277;6062.787802426894;6119.715820550834;6225.284092329259;5928.34788173292;5990.760369585342;6046.183765191643;6055.485283750754;6113.633182009094;6107.877842443215;6108.797423689751;6147.293061298008;6151.015837465984;6153.538484613761;6050.848728781815;6109.192117492145;6121.178620692955;6127.128444391353;6144.047672610753 16:57:07 INFO - PROCESS | 1912 | |1;ext.html;423.8876697675117;449.3888311895822;473.14361006578076;480.83651558470115;526.7497938479223;32306.838465807698;38960;41127.79436102819;41562.37656435152;43533.12933741323;41974.74075777724;42354;42373.57626423735;42379.57620423795;42490.7875460623;6935.757248496304;9063.682771103013;9263.953680231589;9286.628534858632;9301.95349023254;8336.66653333869;11080.778384432306;11309.321440713542;11316.434178291052;11440.370779607118;11282.210245282833;11298.661040168809;11318.830217546743;11334.433278336091;11338.829917551244;11196.272242304223;11237.15721320896;11265.662030139069;11275.436228188557;11299.717507062307;11174.944125279402;11267;11275.66173014811;11290.661280161608;11293.717657058558;39908.60091399107;40079.99800004994;40120.79939600312;40289.59710402888;40298.59701402978;4066.3205826964927;4072.2669919414493;4105.835766569334;4115.300398932159;4158.8336466541305;29539.409211815644;30724.84637576798;30877.228069298224;30889;30899.845500772353;3163.1617621330365;3262.706356427933;3299.7690161688693;3320.8671653133847;3332.916677083068;476049;479516;480937;482340.58829705976;483040;56378.43621563814;57154.428455715744;57495.425045749005;57498.7125064372;57724;62543.6872815633;62741;63043.36956630467;63086.36913630805;63098.053529197095;15688.92155539215;16140.273687684083;16253.593660158354;16259.268332925047;16292.348306067624;11275.943620281847;11574.421278936019;11918.523259069714;12634.431450584561;12756.80864787029;15958.68082638364;16036.919815401081;16107.597310067107;16148.596285092732;16166;6083.513318934474;6105.633661980295;6661.933380666228;6677.163779960452;6762.628055456997;774.5430196184166;830.5515021888234;871.2986046232843;891.9296843787481;892.1123482135188;29500;29937.251568710955;29978.800847966275;30171;30532.08403747891;1056.5932116135343;1066.679996001212;1089.9727506812394;1107.4684151607273;1199.208522375228;15353.692926141637;15438.614034649223;15517.767233491506;15684.68630627404;15739.291731872094;208638;239099;242809;243572;243862;59798.10302845461;60119;60167.699161503915;60187.69906150441;60212.39787602068;1226.693326668333;1426.5292453490324;1431.9069260498034;1433.6200906759925;1446.6166465886338;28537.714622853506;28895.133146005654;29407.558886617146;29884.701152988193;30563.54154687726;50747;51136.74431627818;51217.7439112802;51271.23093153531;51430.971380572926;559647;559873;559919.400806003;560007;561879;23857;24591.50816983686;24793.380165496008;25343.493130137664;39865.80067099762;42635.360469592364;42800.57199427966;43429.78285108554;43461;43676.56323436725;30882.69117308888;33833.83083084667;35271.47092793559;35359.93920182399;36171.457428139125;34861;35337.293254135286;35871.82064089663;35877.461838071926;36053.81973090118;25967.870160649076;34846.65153348434;34964.951051468495;35209.82395088008;35418.468722969694;29981.55027674543;31749;31756.6824331763;31912.88304909317;31933.68066319307;20162.89918550398;22286.66280023204;23312.067517299118;23617.88191059034;23707.1702490412 16:57:07 INFO - PROCESS | 1912 | |2;jquery.html;376.5387400434468;382.5524914582747;389.9317619416602;395.35162333772615;410.5709533537455;15483.612909677264;17566.473005809814;19785;21149.788502114974;21195.57608847822;21215.57568848626;21475.57048859022;21996.450088747788;22049;22195.667064994;6507.577007494509;6819.181698196198;6881.483888708367;6895.55178913369;6979.4416446684145;5807.477327040566;6573.506986975972;6718.83202919926;6776.695048722795;6794.218664853555;6801.28586498418;6801.727930882784;6809.421199198049;6831.248562658128;6834.9658251708925;6546.279909209983;6796.490263230253;6824.658767061627;6826.93173068268;6855;6433.8391540211405;6553.803385898431;6649.833754156137;6674.299198584127;6716.361945615159;29221.561676574867;29848.253793655116;29923.551146732818;30110.397792044256;30368;2838.1911155320636;3014.3669829335868;3043.421749867518;3043.893463728781;3109.129443755752;16843.494695159166;17277.740833887503;17451.38920137789;18012;18529.629407411776;1695.8982461052253;1962.6663467210608;2065.865718728278;2068.772435032145;2071;143357;168471;169628;171971.14014429972;173647;17726.468205953843;23220.3033908983;23443.94502247403;23650;23818.64272035921;10202.28583999113;12591.937040314922;34471.13822154416;40385;40985;6219.968900155561;6372.968135159294;7412.888806667905;8165.918340816515;8312.127226641236;7763.883541746878;8261.297789687824;8273.379496537784;8430.536320502431;8433.325333973267;3633.636636336398;4097.7501861931805;4118.938215926763;4121.732087414309;4216.894577635584;1778.6976214043384;2047.1811275489922;2060.536379314642;2085.7392825896763;2092.571022940324;1023.6373513009727;1039.3400190878847;1052.3422860712055;1061.8619579454776;1074.8656417947757;21699.13203471875;22167;22184.223552175597;22218.55562888733;22280;884.3853521802414;994.1400688404478;1051.3901936876593;1084.6745976207105;1091.3833683968533;9548.761280968032;10485.213608979357;10641.255112142077;11276.097912167006;11297.26567773092;737741;745522;751281;754536;754725.4527454765;156809.21595392178;163073;163808;166061.16969415318;168388.15805920892;1019.6227395863579;1038.7506998320348;1055.350959160111;1063.9787204255872;1065.1106326217541;15212.239388030333;15853.603659908595;18105.909470452563;18656.440306790817;19014.334498293047;42201;58845.117323241044;59178;59339;61559;63722.72554548976;67635;83353;84466.57766711127;86196;11430.314181149157;12107.273563586214;12656.810147847606;13077.411516481972;13127.737445251232;11397.487113080097;13687.04190706641;13696.726065478833;14139.293035347986;14520.273986300854;11666.76666466649;11725.706857328294;11752.529898803952;11995.340256285988;12065.81901271464;11176.441177941233;11268.718282043015;11574;11689.649310520697;11930.642080737245;10071.949640251752;11230.21388502797;11886.465109069939;12095.85089416504;12293.56972505958;9873.50632468388;10043.4476103815;13186.472541098632;13283.468661253442;13384.531541396394;11618.419079046183;11636.592719254786;11861.822072669089;11870.050395968128;11989.920907118401 16:57:07 INFO - PROCESS | 1912 | |3;mootools.html;1460.175001124364;1464.6924145929354;1467.8091848059757;1482.4589025005873;1486.1157611221327;7473;7765.844683106335;8000.119986801455;8497.745067647973;8502.617382217799;7960.283574478296;8222.3833212509;8223.876641850362;8355.373346998984;8370.497770133796;2306.2274138163734;2330.3591512334074;2333.9533209335805;2340.3330050935488;2341.3678306857137;2156.3207589609224;2329.4758679297106;2356.422676444265;2362.1496261345987;2374.8931298091543;2151.946201344971;2282.3723476044056;2336.19401306549;2336.556054349668;2341.6019276723;2285.0517035430275;2291.7937385635205;2311.2719493359537;2322.5006623576014;2323.5826146050895;2280.395695140772;2341.7237605505043;2348;2374.8812559371963;2377.7978871795917;8338.499690018618;8399.202075802788;8403.873941890877;8432.988041435006;8435.493870367798;4238.427812245338;5140.948590514122;5171.5862730981435;5181.4300426953005;5206.906549624583;3291;3382.3390021601094;3475.4091804393315;3533.392306500562;3557.8576856925692;1197.3594127141937;1244.5457408046088;1255.5354518828;1261.5269274022241;1283.505850247652;8974.730758077269;10640.521176546996;10721.83917241242;10782.299150555189;10826.891731082746;3937.783421911822;4158.625723684822;4589.954100458953;4700.247960326331;4737.4788773235605;9296.30277729173;9474.242060635133;9491.098345657152;9493.288003399774;9501.42991420517;4314.913701726011;4316.5467625899455;5223.634345595772;5228.928069745693;5231.895362092813;4113.560253911167;4263.424437700933;4353.499347575006;4743.667943244009;4791.784369703371;3815.141593141521;3854.2291541691775;3950.051987523031;3953.189596132786;3955.20895820837;1163.365965548778;1248.4194849395076;1290.1227165527343;1298.2729671383865;1312.1274352555445;783.2559068884583;832.7127141136298;901.5131828812499;904.4754042655244;927.1007123090591;5461.836144915659;5505.146702261156;5568.916466253009;5726.169705392695;5730.455606717355;934.5000424772714;942.1567696911226;960.7997685300584;979.2802290316612;983.6065573770549;3644.2711457708565;3660.0300920256154;3666.5966743658178;3778.4899038629983;3801.030737162006;8074.838503229903;8382.748517544484;10074.042965918372;10089.747756306004;10094.798104037878;9362.625494980124;9991.900080999243;10001.999800020085;10024.799504010025;10032.749181270527;7583.431242656712;7907.723229686933;7923.16806735296;7927.603619818986;7932;5520.917186242129;5545.223668686306;5633.802816901389;5837.357890631942;5865.442782935615;8880.86678699833;9204.217641500547;9235.861462077939;9292.72121836346;9318.48748318849;9093.499857507635;9190.31072669539;9303.162715355651;9361.719148425558;9415.340926135379;6441.3880681335195;7176.7488137914925;7198.316159964795;7223.2415596361625;7323.450741194325;7953.562554059351;8001.679932802623;8068.7175948841505;8100.675972961251;8108.148644392254;7090.539114957512;7167.892481612885;7210.242924493063;7266.527675701062;7297.379722723415;6997.580145191305;7038.120234970629;7040.295970402919;7128.857422851617;7154.642267886689;6313.65274909866;6317.873642527215;6385.233771947396;6392.488600912009;6404.391582799627;3857.53709554844;4932.753362331798;4946.332245147003;5005.749712514287;5042.697438153723;4499.505054443943;4867.7322747249245;4896.534829191221;4989.675671081368;5153.201253805666 16:57:07 INFO - PROCESS | 1912 | |4;prototype.html;358.9784612923225;361.24319550541617;370.9610490898456;378.43801954098143;379.09396542263977;5168.870778230546;5295.232191332264;5309.920351194731;5444.3194600674915;5444.673319600822;4668.603168730662;4950.826721064764;5191.741592407296;5236.476352364771;5401.243825864382;3653.780773153619;3681.3189559931366;4083.591640835922;4238.830446782125;4262.3393374027;3422.9828850855833;3535.4343305071193;4088.2232375848494;4215.051613386994;4299.237312701793;4186.769727664977;4187.1625674865;4190.5809419058005;4204.747715137086;4230.407742915979;3646.32542979547;4164.83340666373;4182.414461975326;4195.307774217251;4235.343521754133;2994.9550756738668;3719.7681391165356;3759.172981943968;3777.471154038437;3784.6026167252317;5081.339425874649;5199.740012999335;5213.7393130343335;5257.237700533401;5257.553107985826;4121.670266378683;4188.39268306097;4312.072904325585;4623.12160689468;4863.197572400585;5479.479449452296;5670.234518340054;5680.176374425727;5688.146777983295;5688.687122208277;3491;3727.329080765461;4038.808551477321;4060.2285565742413;4100.2414553307435;5671.602987790856;5966.403359664042;5982.312034116088;5999.340072592011;6033.336333003366;4204.096119334357;4210.5157906841105;4213.389058586487;4226.17589570037;4240.915181696349;3647.7264205184715;3948.190620922704;3972.940405893914;4121.8978321056475;4168.541460439349;4344.239758042393;4503.887402814956;4536.206163921301;4559.489193321124;4832.299316599074;4936.358273424432;5297.443768404339;5527.861803454865;5695.430456954271;5752.194692743019;1168.3982748884332;1181.5687274144875;1299.8895093916933;1392.5822253323963;1440.2150827798666;835.578033093284;838.6184286149787;982.2731178927543;982.390917631059;1003.8042581696499;4073.816678249486;4505.977470112629;4950.628702847301;5009;5082;4772.665913386029;4790.449098353664;5343.438938911436;5545.889082218333;5559.388467268597;4517.322401639781;4673.485916549177;5032.169692000852;5043.117454445457;5071.847844564669;2556.501482210951;2773.861306934645;2816.4789513939577;2890.814765945952;2958.0329081160785;5444.646098003617;5519.475649813261;5796;5796.710164491758;5803.970980145156;4683.735391444291;5138.743062846843;5201.765920533585;5443.374011988592;5754.568407369464;5219.5041471060185;5533.806316778893;5734.283214598176;5755.65466072037;5800.970995144998;4779.498152693917;5164.741762911764;5335.893282134413;5579.302587176603;5592.832215033555;5084.339035925306;5097.254726783703;5116.539511443988;5381.919271210857;5452.318460192476;5019.899602008012;5084;5306.469353064663;5772.2207501986695;5905.785330501062;3833.961660383472;3986.325743187926;4014.5146295870713;4025.5974402560682;4054.3310353791294;4943.925841112458;5294.841154765363;5354.651947623392;5410.513053825174;5502.642328248651;5509.641873278224;5586.804461843816;5721.370649228665;5722.3991480895575;5737.282839645044;4855.73750824781;5526.86182845416;5702.743376548148;5710.828675139753;5777.064683331131;4892.584130348818;5058.54473097423;5256.921146182887;5605.691686957257;5638.605297629127;4470.754108524063;5519;5681.829545113653;5755.827325180251;5789.45000224978;4709.764511774329;4726.511148988057;4732.479427263067;5252.606054545848;5623.638072009684 16:57:07 INFO - PROCESS | 1912 | |5;yui.html;589.0810335876031;598.4643743849252;714.3142583120208;824.5671022713077;824.6742536698005;14760.188189649565;15823.683526329467;15830.762538561901;15847.28687209077;15862.286197121142;14563.708725825478;14696;14704.558863234093;14711.411543538246;14803.629909252275;670.1991120610872;833.3250067445389;853.5262929074361;873.6854732296368;878.6485405837684;848.325581162977;869.0440515433005;872.637855290057;874.1913729799942;874.3049275825733;914.0448231597962;923.0907556057278;924.1313165624325;926.0970553710146;926.9814603707922;913.995430022849;916.4821875640242;918.1828172926101;919.9494027828467;921.272194965976;681.7852376501436;694.1808665774385;694.472201127142;697.8150790040643;698.0855079845419;14985;15369.077855328716;15386;15507.379704811794;15787.973781704153;14233.78649320261;14541.27293635314;14638.194899280536;14663.56009319722;14688.192149431778;11823;11854.881451185463;11880.108991825648;11927.98612117971;12405.317707526083;3448.672261179464;3690.907727306812;3713.554373475173;3718.6839118674943;3864.3623802072625;94324;96133.5193324029;96300;97126.5143674277;97711.51144244305;10069.295149339476;10422.687319380431;10958.945205274082;11004.834927476095;11174.329540227613;22546.887265563568;22993;23017.42456438569;23072.307830765105;23129.190478333516;7420.480566360303;7499.737509187153;7514.774556763306;7614.162442131361;7683.423743219281;4162.146759914211;4273.380359847804;4315.3742707307265;4343.826246950087;4345.67407444443;3429.7599168058496;3489.6684814943046;3530.505729197914;3538.663826936437;3547.5565554305713;634.4733870887153;651.771879842061;660.5046215338496;661.163628010567;668.0113432120392;571.7512881896388;653.7809833705762;654.653033892038;665.4676258992787;666.3844410688126;9599.42403455795;9921.900780992097;9961.452120133463;9962.701118966297;9980.550875210633;642.6369101457755;667.3326673326674;672.428435829541;676.2610585515363;700.4046560423599;4133.697885166163;4692.436907571045;4801.567858892717;4817.940053188293;4822;95025.52487237658;97388.51305743426;99228.51157232648;99290;100059;45849.08301833944;76425.6178719114;77382.61308693534;83385;86982.69525957116;73777.26222737704;73825.63087184637;75560.62219688867;75998.86001709978;76439;10991.670249892515;10998.560057597606;11113.277636953895;11195.832062518906;11216.439178041228;31623.20941976469;45068;46687.83280417881;49398;50337.741556459914;58438.831223374444;65397.67301163464;66935.33064669486;68283;70453.29546704673;11875.524979000744;12925.28910909909;12931.418086185957;13158.539451119164;13264.071514994244;13807.286130124983;14938.178400188093;15000.849991499945;15006.849931500981;16039.358425662842;13484.325783710972;13797.517086902311;13927.58217253484;13966.205989628806;14061.789073164115;13464.326783660974;13630.727385452434;14043.438262469386;14086.859131408555;14492.710145796815;10927.398993055458;12728.427220775273;12869.420876060787;12888.742225155633;13384.72666739303;10547.419891905713;11009.449527523751;11064.668059958214;11069.279723019841;11353.262037967506;7573.734919277799;9728.367656102331;9848;10492.632757853438;10503.792063912748 16:57:07 INFO - PROCESS | 1912 | __end_tp_report 16:57:07 INFO - PROCESS | 1912 | __start_cc_report 16:57:07 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,371 16:57:07 INFO - PROCESS | 1912 | __end_cc_report 16:57:07 INFO - PROCESS | 1912 | __startTimestamp1484787427869__endTimestamp 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | ------- Summary: start ------- 16:57:07 INFO - PROCESS | 1912 | Number of tests: 6 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#0] dojo.html Cycles:165 Average:5715.25 Median:5990.76 stddev:664.37 (11.1%) stddev-sans-first:622.16 16:57:07 INFO - PROCESS | 1912 | Values: 2675.8 3338.0 3364.6 3386.3 3427.8 4450.3 5671.8 5706.6 5718.2 5792.9 4854.7 5472.7 5581.1 5687.9 5770.5 4253.4 4351.3 5229.6 5351.5 5361.0 5181.2 5340.9 5356.6 5404.9 5470.3 4999.6 5022.7 5315.4 5330.3 5332.6 5100.7 5121.0 5123.6 5203.3 5252.1 5222.7 5239.1 5298.8 5316.7 5326.3 5623.6 5786.9 5802.6 5868.5 5881.9 6112.6 6164.7 6230.8 6243.8 6244.9 6105.9 6110.8 6155.8 6174.2 6215.7 5768.0 5814.6 5832.5 5840.2 5846.6 6146.0 6201.5 6252.0 6277.8 6282.9 6118.4 6123.3 6137.6 6148.2 6239.1 6248.3 6248.3 6248.8 6250.2 6282.6 6116.7 6122.2 6160.1 6170.8 6203.2 6131.3 6146.7 6178.6 6217.5 6253.4 5044.6 5056.5 5060.7 5062.4 5104.9 4503.7 4512.0 4518.8 4537.1 4537.8 5000.7 5791.4 5815.9 5842.3 5928.4 6127.6 6184.3 6198.2 6227.2 6267.7 5981.7 5981.8 5992.1 6007.8 6020.2 5517.2 5520.8 5532.7 5549.6 5561.1 6216.6 6239.1 6370.7 6381.9 6401.9 5272.6 5711.7 5741.4 6229.1 6265.3 5267.9 5359.7 6390.3 6468.5 6479.1 6085.5 6112.1 6118.4 6149.7 6233.0 5133.9 6160.2 6206.9 6217.1 6229.4 5863.4 5928.9 6053.3 6063.1 6091.8 5692.1 5995.7 6062.8 6119.7 6225.3 5928.3 5990.8 6046.2 6055.5 6113.6 6107.9 6108.8 6147.3 6151.0 6153.5 6050.8 6109.2 6121.2 6127.1 6144.0 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#1] ext.html Cycles:180 Average:57738.12 Median:18583.37 stddev:119447.80 (642.8%) stddev-sans-first:119705.37 16:57:07 INFO - PROCESS | 1912 | Values: 423.9 449.4 473.1 480.8 526.7 32306.8 38960.0 41127.8 41562.4 43533.1 41974.7 42354.0 42373.6 42379.6 42490.8 6935.8 9063.7 9264.0 9286.6 9302.0 8336.7 11080.8 11309.3 11316.4 11440.4 11282.2 11298.7 11318.8 11334.4 11338.8 11196.3 11237.2 11265.7 11275.4 11299.7 11174.9 11267.0 11275.7 11290.7 11293.7 39908.6 40080.0 40120.8 40289.6 40298.6 4066.3 4072.3 4105.8 4115.3 4158.8 29539.4 30724.8 30877.2 30889.0 30899.8 3163.2 3262.7 3299.8 3320.9 3332.9 476049.0 479516.0 480937.0 482340.6 483040.0 56378.4 57154.4 57495.4 57498.7 57724.0 62543.7 62741.0 63043.4 63086.4 63098.1 15688.9 16140.3 16253.6 16259.3 16292.3 11275.9 11574.4 11918.5 12634.4 12756.8 15958.7 16036.9 16107.6 16148.6 16166.0 6083.5 6105.6 6661.9 6677.2 6762.6 774.5 830.6 871.3 891.9 892.1 29500.0 29937.3 29978.8 30171.0 30532.1 1056.6 1066.7 1090.0 1107.5 1199.2 15353.7 15438.6 15517.8 15684.7 15739.3 208638.0 239099.0 242809.0 243572.0 243862.0 59798.1 60119.0 60167.7 60187.7 60212.4 1226.7 1426.5 1431.9 1433.6 1446.6 28537.7 28895.1 29407.6 29884.7 30563.5 50747.0 51136.7 51217.7 51271.2 51431.0 559647.0 559873.0 559919.4 560007.0 561879.0 23857.0 24591.5 24793.4 25343.5 39865.8 42635.4 42800.6 43429.8 43461.0 43676.6 30882.7 33833.8 35271.5 35359.9 36171.5 34861.0 35337.3 35871.8 35877.5 36053.8 25967.9 34846.7 34965.0 35209.8 35418.5 29981.6 31749.0 31756.7 31912.9 31933.7 20162.9 22286.7 23312.1 23617.9 23707.2 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#2] jquery.html Cycles:180 Average:42996.89 Median:21587.35 stddev:125457.07 (581.2%) stddev-sans-first:125768.19 16:57:07 INFO - PROCESS | 1912 | Values: 376.5 382.6 389.9 395.4 410.6 15483.6 17566.5 19785.0 21149.8 21195.6 21215.6 21475.6 21996.5 22049.0 22195.7 6507.6 6819.2 6881.5 6895.6 6979.4 5807.5 6573.5 6718.8 6776.7 6794.2 6801.3 6801.7 6809.4 6831.2 6835.0 6546.3 6796.5 6824.7 6826.9 6855.0 6433.8 6553.8 6649.8 6674.3 6716.4 29221.6 29848.3 29923.6 30110.4 30368.0 2838.2 3014.4 3043.4 3043.9 3109.1 16843.5 17277.7 17451.4 18012.0 18529.6 1695.9 1962.7 2065.9 2068.8 2071.0 143357.0 168471.0 169628.0 171971.1 173647.0 17726.5 23220.3 23443.9 23650.0 23818.6 10202.3 12591.9 34471.1 40385.0 40985.0 6220.0 6373.0 7412.9 8165.9 8312.1 7763.9 8261.3 8273.4 8430.5 8433.3 3633.6 4097.8 4118.9 4121.7 4216.9 1778.7 2047.2 2060.5 2085.7 2092.6 1023.6 1039.3 1052.3 1061.9 1074.9 21699.1 22167.0 22184.2 22218.6 22280.0 884.4 994.1 1051.4 1084.7 1091.4 9548.8 10485.2 10641.3 11276.1 11297.3 737741.0 745522.0 751281.0 754536.0 754725.5 156809.2 163073.0 163808.0 166061.2 168388.2 1019.6 1038.8 1055.4 1064.0 1065.1 15212.2 15853.6 18105.9 18656.4 19014.3 42201.0 58845.1 59178.0 59339.0 61559.0 63722.7 67635.0 83353.0 84466.6 86196.0 11430.3 12107.3 12656.8 13077.4 13127.7 11397.5 13687.0 13696.7 14139.3 14520.3 11666.8 11725.7 11752.5 11995.3 12065.8 11176.4 11268.7 11574.0 11689.6 11930.6 10071.9 11230.2 11886.5 12095.9 12293.6 9873.5 10043.4 13186.5 13283.5 13384.5 11618.4 11636.6 11861.8 11870.1 11989.9 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#3] mootools.html Cycles:180 Average:5386.89 Median:5194.17 stddev:2920.03 (56.2%) stddev-sans-first:2913.31 16:57:07 INFO - PROCESS | 1912 | Values: 1460.2 1464.7 1467.8 1482.5 1486.1 7473.0 7765.8 8000.1 8497.7 8502.6 7960.3 8222.4 8223.9 8355.4 8370.5 2306.2 2330.4 2334.0 2340.3 2341.4 2156.3 2329.5 2356.4 2362.1 2374.9 2151.9 2282.4 2336.2 2336.6 2341.6 2285.1 2291.8 2311.3 2322.5 2323.6 2280.4 2341.7 2348.0 2374.9 2377.8 8338.5 8399.2 8403.9 8433.0 8435.5 4238.4 5140.9 5171.6 5181.4 5206.9 3291.0 3382.3 3475.4 3533.4 3557.9 1197.4 1244.5 1255.5 1261.5 1283.5 8974.7 10640.5 10721.8 10782.3 10826.9 3937.8 4158.6 4590.0 4700.2 4737.5 9296.3 9474.2 9491.1 9493.3 9501.4 4314.9 4316.5 5223.6 5228.9 5231.9 4113.6 4263.4 4353.5 4743.7 4791.8 3815.1 3854.2 3950.1 3953.2 3955.2 1163.4 1248.4 1290.1 1298.3 1312.1 783.3 832.7 901.5 904.5 927.1 5461.8 5505.1 5568.9 5726.2 5730.5 934.5 942.2 960.8 979.3 983.6 3644.3 3660.0 3666.6 3778.5 3801.0 8074.8 8382.7 10074.0 10089.7 10094.8 9362.6 9991.9 10002.0 10024.8 10032.7 7583.4 7907.7 7923.2 7927.6 7932.0 5520.9 5545.2 5633.8 5837.4 5865.4 8880.9 9204.2 9235.9 9292.7 9318.5 9093.5 9190.3 9303.2 9361.7 9415.3 6441.4 7176.7 7198.3 7223.2 7323.5 7953.6 8001.7 8068.7 8100.7 8108.1 7090.5 7167.9 7210.2 7266.5 7297.4 6997.6 7038.1 7040.3 7128.9 7154.6 6313.7 6317.9 6385.2 6392.5 6404.4 3857.5 4932.8 4946.3 5005.7 5042.7 4499.5 4867.7 4896.5 4989.7 5153.2 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#4] prototype.html Cycles:180 Average:4508.97 Median:5014.45 stddev:1339.13 (26.7%) stddev-sans-first:1306.16 16:57:07 INFO - PROCESS | 1912 | Values: 359.0 361.2 371.0 378.4 379.1 5168.9 5295.2 5309.9 5444.3 5444.7 4668.6 4950.8 5191.7 5236.5 5401.2 3653.8 3681.3 4083.6 4238.8 4262.3 3423.0 3535.4 4088.2 4215.1 4299.2 4186.8 4187.2 4190.6 4204.7 4230.4 3646.3 4164.8 4182.4 4195.3 4235.3 2995.0 3719.8 3759.2 3777.5 3784.6 5081.3 5199.7 5213.7 5257.2 5257.6 4121.7 4188.4 4312.1 4623.1 4863.2 5479.5 5670.2 5680.2 5688.1 5688.7 3491.0 3727.3 4038.8 4060.2 4100.2 5671.6 5966.4 5982.3 5999.3 6033.3 4204.1 4210.5 4213.4 4226.2 4240.9 3647.7 3948.2 3972.9 4121.9 4168.5 4344.2 4503.9 4536.2 4559.5 4832.3 4936.4 5297.4 5527.9 5695.4 5752.2 1168.4 1181.6 1299.9 1392.6 1440.2 835.6 838.6 982.3 982.4 1003.8 4073.8 4506.0 4950.6 5009.0 5082.0 4772.7 4790.4 5343.4 5545.9 5559.4 4517.3 4673.5 5032.2 5043.1 5071.8 2556.5 2773.9 2816.5 2890.8 2958.0 5444.6 5519.5 5796.0 5796.7 5804.0 4683.7 5138.7 5201.8 5443.4 5754.6 5219.5 5533.8 5734.3 5755.7 5801.0 4779.5 5164.7 5335.9 5579.3 5592.8 5084.3 5097.3 5116.5 5381.9 5452.3 5019.9 5084.0 5306.5 5772.2 5905.8 3834.0 3986.3 4014.5 4025.6 4054.3 4943.9 5294.8 5354.7 5410.5 5502.6 5509.6 5586.8 5721.4 5722.4 5737.3 4855.7 5526.9 5702.7 5710.8 5777.1 4892.6 5058.5 5256.9 5605.7 5638.6 4470.8 5519.0 5681.8 5755.8 5789.5 4709.8 4726.5 4732.5 5252.6 5623.6 16:57:07 INFO - PROCESS | 1912 | 16:57:07 INFO - PROCESS | 1912 | [#5] yui.html Cycles:180 Average:19457.13 Median:45458.54 stddev:27063.28 (59.5%) stddev-sans-first:27102.12 16:57:07 INFO - PROCESS | 1912 | Values: 589.1 598.5 714.3 824.6 824.7 14760.2 15823.7 15830.8 15847.3 15862.3 14563.7 14696.0 14704.6 14711.4 14803.6 670.2 833.3 853.5 873.7 878.6 848.3 869.0 872.6 874.2 874.3 914.0 923.1 924.1 926.1 927.0 914.0 916.5 918.2 919.9 921.3 681.8 694.2 694.5 697.8 698.1 14985.0 15369.1 15386.0 15507.4 15788.0 14233.8 14541.3 14638.2 14663.6 14688.2 11823.0 11854.9 11880.1 11928.0 12405.3 3448.7 3690.9 3713.6 3718.7 3864.4 94324.0 96133.5 96300.0 97126.5 97711.5 10069.3 10422.7 10958.9 11004.8 11174.3 22546.9 22993.0 23017.4 23072.3 23129.2 7420.5 7499.7 7514.8 7614.2 7683.4 4162.1 4273.4 4315.4 4343.8 4345.7 3429.8 3489.7 3530.5 3538.7 3547.6 634.5 651.8 660.5 661.2 668.0 571.8 653.8 654.7 665.5 666.4 9599.4 9921.9 9961.5 9962.7 9980.6 642.6 667.3 672.4 676.3 700.4 4133.7 4692.4 4801.6 4817.9 4822.0 95025.5 97388.5 99228.5 99290.0 100059.0 45849.1 76425.6 77382.6 83385.0 86982.7 73777.3 73825.6 75560.6 75998.9 76439.0 10991.7 10998.6 11113.3 11195.8 11216.4 31623.2 45068.0 46687.8 49398.0 50337.7 58438.8 65397.7 66935.3 68283.0 70453.3 11875.5 12925.3 12931.4 13158.5 13264.1 13807.3 14938.2 15000.8 15006.8 16039.4 13484.3 13797.5 13927.6 13966.2 14061.8 13464.3 13630.7 14043.4 14086.9 14492.7 10927.4 12728.4 12869.4 12888.7 13384.7 10547.4 11009.4 11064.7 11069.3 11353.3 7573.7 9728.4 9848.0 10492.6 10503.8 16:57:07 INFO - PROCESS | 1912 | -------- Summary: end -------- 16:57:07 INFO - PROCESS | 1912 | 16:57:08 INFO - TEST-INFO | 1912: exit 0 16:57:08 INFO - TEST-OK | dromaeo_css | took 1115411ms 16:57:08 INFO - TEST-START | kraken 16:57:08 INFO - operating with platform_type : mac_ 16:57:08 INFO - Initialising browser for kraken test... 16:57:08 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpPQBKJs/profile http://localhost:56179/getInfo.html) 16:57:11 INFO - PROCESS | 1916 | 2017-01-18 16:57:11.443 plugin-container[1917:23010] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 16:57:11 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:11 INFO - PROCESS | 1916 | 2017-01-18 16:57:11.445 plugin-container[1917:23010] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 16:57:11 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:11 INFO - PROCESS | 1916 | 2017-01-18 16:57:11.445 plugin-container[1917:23010] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:57:11 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 16:57:11 INFO - PROCESS | 1916 | colorDepth:24 16:57:11 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 16:57:11 INFO - PROCESS | 1916 | __metrics 16:57:11 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 16:57:12 INFO - TEST-INFO | 1916: exit 0 16:57:12 INFO - Browser initialized. 16:57:12 INFO - Running cycle 1/1 for kraken test... 16:57:12 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpPQBKJs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:14 INFO - PROCESS | 1918 | 2017-01-18 16:57:14.840 plugin-container[1919:23137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 16:57:14 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:14 INFO - PROCESS | 1918 | 2017-01-18 16:57:14.841 plugin-container[1919:23137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 16:57:14 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:14 INFO - PROCESS | 1918 | 2017-01-18 16:57:14.841 plugin-container[1919:23137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:57:24 INFO - PROCESS | 1918 | RSS: Main: 232374272 16:57:24 INFO - PROCESS | 1918 | 16:57:27 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-beat-detection) 16:57:28 INFO - PROCESS | 1918 | RSS: Main: 245993472 16:57:28 INFO - PROCESS | 1918 | 16:57:31 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-dft) 16:57:31 INFO - PROCESS | 1918 | RSS: Main: 253661184 16:57:31 INFO - PROCESS | 1918 | 16:57:35 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-fft) 16:57:36 INFO - PROCESS | 1918 | RSS: Main: 256364544 16:57:36 INFO - PROCESS | 1918 | 16:57:38 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56179/tests/kraken/driver.html?testName=audio-oscillator) 16:57:38 INFO - PROCESS | 1918 | RSS: Main: 258560000 16:57:38 INFO - PROCESS | 1918 | 16:57:40 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:57:41 INFO - PROCESS | 1918 | RSS: Main: 259649536 16:57:41 INFO - PROCESS | 1918 | 16:57:44 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-darkroom) 16:57:44 INFO - PROCESS | 1918 | RSS: Main: 264978432 16:57:44 INFO - PROCESS | 1918 | 16:57:48 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56179/tests/kraken/driver.html?testName=imaging-desaturate) 16:57:48 INFO - PROCESS | 1918 | RSS: Main: 263958528 16:57:48 INFO - PROCESS | 1918 | 16:57:52 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56179/tests/kraken/driver.html?testName=json-parse-financial) 16:57:52 INFO - PROCESS | 1918 | RSS: Main: 263766016 16:57:52 INFO - PROCESS | 1918 | 16:57:54 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56179/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:57:54 INFO - PROCESS | 1918 | RSS: Main: 263450624 16:57:54 INFO - PROCESS | 1918 | 16:57:56 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:57:57 INFO - PROCESS | 1918 | RSS: Main: 263397376 16:57:57 INFO - PROCESS | 1918 | 16:57:59 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:58:00 INFO - PROCESS | 1918 | RSS: Main: 263319552 16:58:00 INFO - PROCESS | 1918 | 16:58:03 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:58:04 INFO - PROCESS | 1918 | RSS: Main: 263725056 16:58:04 INFO - PROCESS | 1918 | 16:58:07 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:58:07 INFO - PROCESS | 1918 | RSS: Main: 263487488 16:58:07 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:56179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56179/tests/kraken/driver.html?testName=ai-astar) 16:58:09 INFO - PROCESS | 1918 | RSS: Main: 266821632 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | __start_tp_report 16:58:09 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 16:58:09 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 16:58:09 INFO - PROCESS | 1918 | |i|pagename|runs| 16:58:09 INFO - PROCESS | 1918 | |0;ai-astar;108;141;163;119;116;120;116;127;170;122 16:58:09 INFO - PROCESS | 1918 | |1;audio-beat-detection;152;115;117;148;111;142;144;113;144;128 16:58:09 INFO - PROCESS | 1918 | |2;audio-dft;201;272;251;201;197;199;197;191;192;199 16:58:09 INFO - PROCESS | 1918 | |3;audio-fft;81;62;58;87;61;62;69;75;95;83 16:58:09 INFO - PROCESS | 1918 | |4;audio-oscillator;90;72;84;74;71;73;82;70;71;81 16:58:09 INFO - PROCESS | 1918 | |5;imaging-gaussian-blur;136;110;90;97;89;86;90;87;89;84 16:58:09 INFO - PROCESS | 1918 | |6;imaging-darkroom;106;176;131;124;174;172;100;99;107;171 16:58:09 INFO - PROCESS | 1918 | |7;imaging-desaturate;111;146;92;148;77;147;146;127;91;142 16:58:09 INFO - PROCESS | 1918 | |8;json-parse-financial;62;61;61;64;62;62;63;62;62;62 16:58:09 INFO - PROCESS | 1918 | |9;json-stringify-tinderbox;73;76;68;76;76;72;70;70;75;71 16:58:09 INFO - PROCESS | 1918 | |10;stanford-crypto-aes;89;90;92;85;98;95;88;88;80;101 16:58:09 INFO - PROCESS | 1918 | |11;stanford-crypto-ccm;154;162;152;154;163;159;153;150;211;190 16:58:09 INFO - PROCESS | 1918 | |12;stanford-crypto-pbkdf2;171;179;244;212;176;175;172;172;228;174 16:58:09 INFO - PROCESS | 1918 | |13;stanford-crypto-sha256-iterative;68;70;69;67;69;66;69;69;68;68 16:58:09 INFO - PROCESS | 1918 | __end_tp_report 16:58:09 INFO - PROCESS | 1918 | __start_cc_report 16:58:09 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,907 16:58:09 INFO - PROCESS | 1918 | __end_cc_report 16:58:09 INFO - PROCESS | 1918 | __startTimestamp1484787489824__endTimestamp 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | ------- Summary: start ------- 16:58:09 INFO - PROCESS | 1918 | Number of tests: 14 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#0] ai-astar Cycles:10 Average:130.20 Median:124.50 stddev:21.03 (16.9%) stddev-sans-first:20.71 16:58:09 INFO - PROCESS | 1918 | Values: 108.0 141.0 163.0 119.0 116.0 120.0 116.0 127.0 170.0 122.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:143.00 stddev:16.24 (11.4%) stddev-sans-first:15.41 16:58:09 INFO - PROCESS | 1918 | Values: 152.0 115.0 117.0 148.0 111.0 142.0 144.0 113.0 144.0 128.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#2] audio-dft Cycles:10 Average:210.00 Median:200.00 stddev:27.79 (13.9%) stddev-sans-first:29.29 16:58:09 INFO - PROCESS | 1918 | Values: 201.0 272.0 251.0 201.0 197.0 199.0 197.0 191.0 192.0 199.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#3] audio-fft Cycles:10 Average:73.30 Median:78.00 stddev:12.80 (16.4%) stddev-sans-first:13.27 16:58:09 INFO - PROCESS | 1918 | Values: 81.0 62.0 58.0 87.0 61.0 62.0 69.0 75.0 95.0 83.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.50 stddev:6.91 (8.9%) stddev-sans-first:5.43 16:58:09 INFO - PROCESS | 1918 | Values: 90.0 72.0 84.0 74.0 71.0 73.0 82.0 70.0 71.0 81.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:89.00 stddev:15.96 (17.9%) stddev-sans-first:7.87 16:58:09 INFO - PROCESS | 1918 | Values: 136.0 110.0 90.0 97.0 89.0 86.0 90.0 87.0 89.0 84.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#6] imaging-darkroom Cycles:10 Average:136.00 Median:171.50 stddev:33.57 (19.6%) stddev-sans-first:33.80 16:58:09 INFO - PROCESS | 1918 | Values: 106.0 176.0 131.0 124.0 174.0 172.0 100.0 99.0 107.0 171.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#7] imaging-desaturate Cycles:10 Average:122.70 Median:147.50 stddev:27.65 (18.7%) stddev-sans-first:29.00 16:58:09 INFO - PROCESS | 1918 | Values: 111.0 146.0 92.0 148.0 77.0 147.0 146.0 127.0 91.0 142.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:0.88 (1.4%) stddev-sans-first:0.93 16:58:09 INFO - PROCESS | 1918 | Values: 62.0 61.0 61.0 64.0 62.0 62.0 63.0 62.0 62.0 62.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:74.00 stddev:2.95 (4.0%) stddev-sans-first:3.12 16:58:09 INFO - PROCESS | 1918 | Values: 73.0 76.0 68.0 76.0 76.0 72.0 70.0 70.0 75.0 71.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:89.50 stddev:6.19 (6.9%) stddev-sans-first:6.53 16:58:09 INFO - PROCESS | 1918 | Values: 89.0 90.0 92.0 85.0 98.0 95.0 88.0 88.0 80.0 101.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#11] stanford-crypto-ccm Cycles:10 Average:164.80 Median:160.50 stddev:19.92 (12.4%) stddev-sans-first:20.74 16:58:09 INFO - PROCESS | 1918 | Values: 154.0 162.0 152.0 154.0 163.0 159.0 153.0 150.0 211.0 190.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:177.50 stddev:27.18 (15.3%) stddev-sans-first:27.92 16:58:09 INFO - PROCESS | 1918 | Values: 171.0 179.0 244.0 212.0 176.0 175.0 172.0 172.0 228.0 174.0 16:58:09 INFO - PROCESS | 1918 | 16:58:09 INFO - PROCESS | 1918 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 16:58:09 INFO - PROCESS | 1918 | Values: 68.0 70.0 69.0 67.0 69.0 66.0 69.0 69.0 68.0 68.0 16:58:09 INFO - PROCESS | 1918 | -------- Summary: end -------- 16:58:09 INFO - PROCESS | 1918 | 16:58:10 INFO - TEST-INFO | 1918: exit 0 16:58:10 INFO - TEST-OK | kraken | took 62153ms 16:58:10 INFO - SUITE-END | took 1177s 16:58:10 INFO - Completed test suite (00:19:37) 16:58:10 INFO - javascript benchmark 16:58:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7369.703916383929, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2675.7591816736494, 3337.9819155157666, 3364.5794275715534, 3386.2719515304207, 3427.828608569571, 4450.310201918704, 5671.8014869479575, 5706.572007099463, 5718.228039214705, 5792.855178620536, 4854.660173787836, 5472.671639701621, 5581.079121944874, 5687.943120568793, 5770.480656740903, 4253.447051883266, 4351.325544540586, 5229.581633469333, 5351.518363347298, 5361.0459802090345, 5181.1969144782615, 5340.866478338054, 5356.625036247464, 5404.891902161954, 5470.343558772953, 4999.600031997468, 5022.748862556894, 5315.415304316542, 5330.28041214435, 5332.643795903527, 5100.744962751847, 5120.974395128038, 5123.641345105844, 5203.297554830087, 5252.080885844962, 5222.738863056832, 5239.13554263546, 5298.761555730001, 5316.680999140025, 5326.307580014612, 5623.634463759842, 5786.88426231473, 5802.622829516068, 5868.471837534642, 5881.85295367615, 6112.5946701277735, 6164.6917654117115, 6230.781922632731, 6243.781467648656, 6244.907141250308, 6105.914670190231, 6110.771402862501, 6155.784547540815, 6174.228221472316, 6215.689215539205, 5768.048272035151, 5814.592978491507, 5832.504237139806, 5840.153177789197, 5846.590738648296, 6146.0473626587955, 6201.503879689614, 6252.0, 6277.843053923689, 6282.905756413657, 6118.4059889896, 6123.313124872426, 6137.570370074052, 6148.200733904653, 6239.095331176954, 6248.31268560458, 6248.31268560458, 6248.812535623939, 6250.249970003629, 6282.623042617458, 6116.731440059295, 6122.245686735438, 6160.10678451631, 6170.81487555374, 6203.193584833943, 6131.325554189036, 6146.692665366714, 6178.598391104564, 6217.533684973645, 6253.37466253371, 5044.621653376012, 5056.494350564914, 5060.696358218519, 5062.443131255559, 5104.897902041938, 4503.684742068024, 4512.0, 4518.819247230074, 4537.0925814837165, 4537.77311134442, 5000.699958002531, 5791.449812267828, 5815.88368232633, 5842.269716285465, 5928.377520360386, 6127.617148554337, 6184.257889053342, 6198.2252218472695, 6227.221597300338, 6267.655278959701, 5981.700914954235, 5981.790637327673, 5992.121911431232, 6007.759689612454, 6020.187274717857, 5517.172424136332, 5520.751566179531, 5532.6680399176175, 5549.6115271931385, 5561.110222364402, 6216.595921265194, 6239.064140378889, 6370.713317900613, 6381.87236255263, 6401.935980640134, 5272.604554658493, 5711.685857277889, 5741.368449470471, 6229.065640153832, 6265.342139075331, 5267.920981185362, 5359.651622644672, 6390.297067322684, 6468.514861385321, 6479.060536222127, 6085.4523092921845, 6112.1387513176505, 6118.388161183846, 6149.72326245329, 6233.0, 5133.922991155061, 6160.168377269011, 6206.906896396467, 6217.098520714561, 6229.408206220402, 5863.384344643751, 5928.911066334094, 6053.334133245251, 6063.090536419572, 6091.7563297467605, 5692.089265717465, 5995.730192141277, 6062.787802426894, 6119.715820550834, 6225.284092329259, 5928.34788173292, 5990.760369585342, 6046.183765191643, 6055.485283750754, 6113.633182009094, 6107.877842443215, 6108.797423689751, 6147.293061298008, 6151.015837465984, 6153.538484613761, 6050.848728781815, 6109.192117492145, 6121.178620692955, 6127.128444391353, 6144.047672610753], "value": 5673.467288872415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.8876697675117, 449.3888311895822, 473.14361006578076, 480.83651558470115, 526.7497938479223, 32306.838465807698, 38960.0, 41127.79436102819, 41562.37656435152, 43533.12933741323, 41974.74075777724, 42354.0, 42373.57626423735, 42379.57620423795, 42490.7875460623, 6935.757248496304, 9063.682771103013, 9263.953680231589, 9286.628534858632, 9301.95349023254, 8336.66653333869, 11080.778384432306, 11309.321440713542, 11316.434178291052, 11440.370779607118, 11282.210245282833, 11298.661040168809, 11318.830217546743, 11334.433278336091, 11338.829917551244, 11196.272242304223, 11237.15721320896, 11265.662030139069, 11275.436228188557, 11299.717507062307, 11174.944125279402, 11267.0, 11275.66173014811, 11290.661280161608, 11293.717657058558, 39908.60091399107, 40079.99800004994, 40120.79939600312, 40289.59710402888, 40298.59701402978, 4066.3205826964927, 4072.2669919414493, 4105.835766569334, 4115.300398932159, 4158.8336466541305, 29539.409211815644, 30724.84637576798, 30877.228069298224, 30889.0, 30899.845500772353, 3163.1617621330365, 3262.706356427933, 3299.7690161688693, 3320.8671653133847, 3332.916677083068, 476049.0, 479516.0, 480937.0, 482340.58829705976, 483040.0, 56378.43621563814, 57154.428455715744, 57495.425045749005, 57498.7125064372, 57724.0, 62543.6872815633, 62741.0, 63043.36956630467, 63086.36913630805, 63098.053529197095, 15688.92155539215, 16140.273687684083, 16253.593660158354, 16259.268332925047, 16292.348306067624, 11275.943620281847, 11574.421278936019, 11918.523259069714, 12634.431450584561, 12756.80864787029, 15958.68082638364, 16036.919815401081, 16107.597310067107, 16148.596285092732, 16166.0, 6083.513318934474, 6105.633661980295, 6661.933380666228, 6677.163779960452, 6762.628055456997, 774.5430196184166, 830.5515021888234, 871.2986046232843, 891.9296843787481, 892.1123482135188, 29500.0, 29937.251568710955, 29978.800847966275, 30171.0, 30532.08403747891, 1056.5932116135343, 1066.679996001212, 1089.9727506812394, 1107.4684151607273, 1199.208522375228, 15353.692926141637, 15438.614034649223, 15517.767233491506, 15684.68630627404, 15739.291731872094, 208638.0, 239099.0, 242809.0, 243572.0, 243862.0, 59798.10302845461, 60119.0, 60167.699161503915, 60187.69906150441, 60212.39787602068, 1226.693326668333, 1426.5292453490324, 1431.9069260498034, 1433.6200906759925, 1446.6166465886338, 28537.714622853506, 28895.133146005654, 29407.558886617146, 29884.701152988193, 30563.54154687726, 50747.0, 51136.74431627818, 51217.7439112802, 51271.23093153531, 51430.971380572926, 559647.0, 559873.0, 559919.400806003, 560007.0, 561879.0, 23857.0, 24591.50816983686, 24793.380165496008, 25343.493130137664, 39865.80067099762, 42635.360469592364, 42800.57199427966, 43429.78285108554, 43461.0, 43676.56323436725, 30882.69117308888, 33833.83083084667, 35271.47092793559, 35359.93920182399, 36171.457428139125, 34861.0, 35337.293254135286, 35871.82064089663, 35877.461838071926, 36053.81973090118, 25967.870160649076, 34846.65153348434, 34964.951051468495, 35209.82395088008, 35418.468722969694, 29981.55027674543, 31749.0, 31756.6824331763, 31912.88304909317, 31933.68066319307, 20162.89918550398, 22286.66280023204, 23312.067517299118, 23617.88191059034, 23707.1702490412], "value": 19189.213814399038, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.5387400434468, 382.5524914582747, 389.9317619416602, 395.35162333772615, 410.5709533537455, 15483.612909677264, 17566.473005809814, 19785.0, 21149.788502114974, 21195.57608847822, 21215.57568848626, 21475.57048859022, 21996.450088747788, 22049.0, 22195.667064994, 6507.577007494509, 6819.181698196198, 6881.483888708367, 6895.55178913369, 6979.4416446684145, 5807.477327040566, 6573.506986975972, 6718.83202919926, 6776.695048722795, 6794.218664853555, 6801.28586498418, 6801.727930882784, 6809.421199198049, 6831.248562658128, 6834.9658251708925, 6546.279909209983, 6796.490263230253, 6824.658767061627, 6826.93173068268, 6855.0, 6433.8391540211405, 6553.803385898431, 6649.833754156137, 6674.299198584127, 6716.361945615159, 29221.561676574867, 29848.253793655116, 29923.551146732818, 30110.397792044256, 30368.0, 2838.1911155320636, 3014.3669829335868, 3043.421749867518, 3043.893463728781, 3109.129443755752, 16843.494695159166, 17277.740833887503, 17451.38920137789, 18012.0, 18529.629407411776, 1695.8982461052253, 1962.6663467210608, 2065.865718728278, 2068.772435032145, 2071.0, 143357.0, 168471.0, 169628.0, 171971.14014429972, 173647.0, 17726.468205953843, 23220.3033908983, 23443.94502247403, 23650.0, 23818.64272035921, 10202.28583999113, 12591.937040314922, 34471.13822154416, 40385.0, 40985.0, 6219.968900155561, 6372.968135159294, 7412.888806667905, 8165.918340816515, 8312.127226641236, 7763.883541746878, 8261.297789687824, 8273.379496537784, 8430.536320502431, 8433.325333973267, 3633.636636336398, 4097.7501861931805, 4118.938215926763, 4121.732087414309, 4216.894577635584, 1778.6976214043384, 2047.1811275489922, 2060.536379314642, 2085.7392825896763, 2092.571022940324, 1023.6373513009727, 1039.3400190878847, 1052.3422860712055, 1061.8619579454776, 1074.8656417947757, 21699.13203471875, 22167.0, 22184.223552175597, 22218.55562888733, 22280.0, 884.3853521802414, 994.1400688404478, 1051.3901936876593, 1084.6745976207105, 1091.3833683968533, 9548.761280968032, 10485.213608979357, 10641.255112142077, 11276.097912167006, 11297.26567773092, 737741.0, 745522.0, 751281.0, 754536.0, 754725.4527454765, 156809.21595392178, 163073.0, 163808.0, 166061.16969415318, 168388.15805920892, 1019.6227395863579, 1038.7506998320348, 1055.350959160111, 1063.9787204255872, 1065.1106326217541, 15212.239388030333, 15853.603659908595, 18105.909470452563, 18656.440306790817, 19014.334498293047, 42201.0, 58845.117323241044, 59178.0, 59339.0, 61559.0, 63722.72554548976, 67635.0, 83353.0, 84466.57766711127, 86196.0, 11430.314181149157, 12107.273563586214, 12656.810147847606, 13077.411516481972, 13127.737445251232, 11397.487113080097, 13687.04190706641, 13696.726065478833, 14139.293035347986, 14520.273986300854, 11666.76666466649, 11725.706857328294, 11752.529898803952, 11995.340256285988, 12065.81901271464, 11176.441177941233, 11268.718282043015, 11574.0, 11689.649310520697, 11930.642080737245, 10071.949640251752, 11230.21388502797, 11886.465109069939, 12095.85089416504, 12293.56972505958, 9873.50632468388, 10043.4476103815, 13186.472541098632, 13283.468661253442, 13384.531541396394, 11618.419079046183, 11636.592719254786, 11861.822072669089, 11870.050395968128, 11989.920907118401], "value": 11006.91660451682, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.175001124364, 1464.6924145929354, 1467.8091848059757, 1482.4589025005873, 1486.1157611221327, 7473.0, 7765.844683106335, 8000.119986801455, 8497.745067647973, 8502.617382217799, 7960.283574478296, 8222.3833212509, 8223.876641850362, 8355.373346998984, 8370.497770133796, 2306.2274138163734, 2330.3591512334074, 2333.9533209335805, 2340.3330050935488, 2341.3678306857137, 2156.3207589609224, 2329.4758679297106, 2356.422676444265, 2362.1496261345987, 2374.8931298091543, 2151.946201344971, 2282.3723476044056, 2336.19401306549, 2336.556054349668, 2341.6019276723, 2285.0517035430275, 2291.7937385635205, 2311.2719493359537, 2322.5006623576014, 2323.5826146050895, 2280.395695140772, 2341.7237605505043, 2348.0, 2374.8812559371963, 2377.7978871795917, 8338.499690018618, 8399.202075802788, 8403.873941890877, 8432.988041435006, 8435.493870367798, 4238.427812245338, 5140.948590514122, 5171.5862730981435, 5181.4300426953005, 5206.906549624583, 3291.0, 3382.3390021601094, 3475.4091804393315, 3533.392306500562, 3557.8576856925692, 1197.3594127141937, 1244.5457408046088, 1255.5354518828, 1261.5269274022241, 1283.505850247652, 8974.730758077269, 10640.521176546996, 10721.83917241242, 10782.299150555189, 10826.891731082746, 3937.783421911822, 4158.625723684822, 4589.954100458953, 4700.247960326331, 4737.4788773235605, 9296.30277729173, 9474.242060635133, 9491.098345657152, 9493.288003399774, 9501.42991420517, 4314.913701726011, 4316.5467625899455, 5223.634345595772, 5228.928069745693, 5231.895362092813, 4113.560253911167, 4263.424437700933, 4353.499347575006, 4743.667943244009, 4791.784369703371, 3815.141593141521, 3854.2291541691775, 3950.051987523031, 3953.189596132786, 3955.20895820837, 1163.365965548778, 1248.4194849395076, 1290.1227165527343, 1298.2729671383865, 1312.1274352555445, 783.2559068884583, 832.7127141136298, 901.5131828812499, 904.4754042655244, 927.1007123090591, 5461.836144915659, 5505.146702261156, 5568.916466253009, 5726.169705392695, 5730.455606717355, 934.5000424772714, 942.1567696911226, 960.7997685300584, 979.2802290316612, 983.6065573770549, 3644.2711457708565, 3660.0300920256154, 3666.5966743658178, 3778.4899038629983, 3801.030737162006, 8074.838503229903, 8382.748517544484, 10074.042965918372, 10089.747756306004, 10094.798104037878, 9362.625494980124, 9991.900080999243, 10001.999800020085, 10024.799504010025, 10032.749181270527, 7583.431242656712, 7907.723229686933, 7923.16806735296, 7927.603619818986, 7932.0, 5520.917186242129, 5545.223668686306, 5633.802816901389, 5837.357890631942, 5865.442782935615, 8880.86678699833, 9204.217641500547, 9235.861462077939, 9292.72121836346, 9318.48748318849, 9093.499857507635, 9190.31072669539, 9303.162715355651, 9361.719148425558, 9415.340926135379, 6441.3880681335195, 7176.7488137914925, 7198.316159964795, 7223.2415596361625, 7323.450741194325, 7953.562554059351, 8001.679932802623, 8068.7175948841505, 8100.675972961251, 8108.148644392254, 7090.539114957512, 7167.892481612885, 7210.242924493063, 7266.527675701062, 7297.379722723415, 6997.580145191305, 7038.120234970629, 7040.295970402919, 7128.857422851617, 7154.642267886689, 6313.65274909866, 6317.873642527215, 6385.233771947396, 6392.488600912009, 6404.391582799627, 3857.53709554844, 4932.753362331798, 4946.332245147003, 5005.749712514287, 5042.697438153723, 4499.505054443943, 4867.7322747249245, 4896.534829191221, 4989.675671081368, 5153.201253805666], "value": 4397.156456766975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.9784612923225, 361.24319550541617, 370.9610490898456, 378.43801954098143, 379.09396542263977, 5168.870778230546, 5295.232191332264, 5309.920351194731, 5444.3194600674915, 5444.673319600822, 4668.603168730662, 4950.826721064764, 5191.741592407296, 5236.476352364771, 5401.243825864382, 3653.780773153619, 3681.3189559931366, 4083.591640835922, 4238.830446782125, 4262.3393374027, 3422.9828850855833, 3535.4343305071193, 4088.2232375848494, 4215.051613386994, 4299.237312701793, 4186.769727664977, 4187.1625674865, 4190.5809419058005, 4204.747715137086, 4230.407742915979, 3646.32542979547, 4164.83340666373, 4182.414461975326, 4195.307774217251, 4235.343521754133, 2994.9550756738668, 3719.7681391165356, 3759.172981943968, 3777.471154038437, 3784.6026167252317, 5081.339425874649, 5199.740012999335, 5213.7393130343335, 5257.237700533401, 5257.553107985826, 4121.670266378683, 4188.39268306097, 4312.072904325585, 4623.12160689468, 4863.197572400585, 5479.479449452296, 5670.234518340054, 5680.176374425727, 5688.146777983295, 5688.687122208277, 3491.0, 3727.329080765461, 4038.808551477321, 4060.2285565742413, 4100.2414553307435, 5671.602987790856, 5966.403359664042, 5982.312034116088, 5999.340072592011, 6033.336333003366, 4204.096119334357, 4210.5157906841105, 4213.389058586487, 4226.17589570037, 4240.915181696349, 3647.7264205184715, 3948.190620922704, 3972.940405893914, 4121.8978321056475, 4168.541460439349, 4344.239758042393, 4503.887402814956, 4536.206163921301, 4559.489193321124, 4832.299316599074, 4936.358273424432, 5297.443768404339, 5527.861803454865, 5695.430456954271, 5752.194692743019, 1168.3982748884332, 1181.5687274144875, 1299.8895093916933, 1392.5822253323963, 1440.2150827798666, 835.578033093284, 838.6184286149787, 982.2731178927543, 982.390917631059, 1003.8042581696499, 4073.816678249486, 4505.977470112629, 4950.628702847301, 5009.0, 5082.0, 4772.665913386029, 4790.449098353664, 5343.438938911436, 5545.889082218333, 5559.388467268597, 4517.322401639781, 4673.485916549177, 5032.169692000852, 5043.117454445457, 5071.847844564669, 2556.501482210951, 2773.861306934645, 2816.4789513939577, 2890.814765945952, 2958.0329081160785, 5444.646098003617, 5519.475649813261, 5796.0, 5796.710164491758, 5803.970980145156, 4683.735391444291, 5138.743062846843, 5201.765920533585, 5443.374011988592, 5754.568407369464, 5219.5041471060185, 5533.806316778893, 5734.283214598176, 5755.65466072037, 5800.970995144998, 4779.498152693917, 5164.741762911764, 5335.893282134413, 5579.302587176603, 5592.832215033555, 5084.339035925306, 5097.254726783703, 5116.539511443988, 5381.919271210857, 5452.318460192476, 5019.899602008012, 5084.0, 5306.469353064663, 5772.2207501986695, 5905.785330501062, 3833.961660383472, 3986.325743187926, 4014.5146295870713, 4025.5974402560682, 4054.3310353791294, 4943.925841112458, 5294.841154765363, 5354.651947623392, 5410.513053825174, 5502.642328248651, 5509.641873278224, 5586.804461843816, 5721.370649228665, 5722.3991480895575, 5737.282839645044, 4855.73750824781, 5526.86182845416, 5702.743376548148, 5710.828675139753, 5777.064683331131, 4892.584130348818, 5058.54473097423, 5256.921146182887, 5605.691686957257, 5638.605297629127, 4470.754108524063, 5519.0, 5681.829545113653, 5755.827325180251, 5789.45000224978, 4709.764511774329, 4726.511148988057, 4732.479427263067, 5252.606054545848, 5623.638072009684], "value": 4100.459534610404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.0810335876031, 598.4643743849252, 714.3142583120208, 824.5671022713077, 824.6742536698005, 14760.188189649565, 15823.683526329467, 15830.762538561901, 15847.28687209077, 15862.286197121142, 14563.708725825478, 14696.0, 14704.558863234093, 14711.411543538246, 14803.629909252275, 670.1991120610872, 833.3250067445389, 853.5262929074361, 873.6854732296368, 878.6485405837684, 848.325581162977, 869.0440515433005, 872.637855290057, 874.1913729799942, 874.3049275825733, 914.0448231597962, 923.0907556057278, 924.1313165624325, 926.0970553710146, 926.9814603707922, 913.995430022849, 916.4821875640242, 918.1828172926101, 919.9494027828467, 921.272194965976, 681.7852376501436, 694.1808665774385, 694.472201127142, 697.8150790040643, 698.0855079845419, 14985.0, 15369.077855328716, 15386.0, 15507.379704811794, 15787.973781704153, 14233.78649320261, 14541.27293635314, 14638.194899280536, 14663.56009319722, 14688.192149431778, 11823.0, 11854.881451185463, 11880.108991825648, 11927.98612117971, 12405.317707526083, 3448.672261179464, 3690.907727306812, 3713.554373475173, 3718.6839118674943, 3864.3623802072625, 94324.0, 96133.5193324029, 96300.0, 97126.5143674277, 97711.51144244305, 10069.295149339476, 10422.687319380431, 10958.945205274082, 11004.834927476095, 11174.329540227613, 22546.887265563568, 22993.0, 23017.42456438569, 23072.307830765105, 23129.190478333516, 7420.480566360303, 7499.737509187153, 7514.774556763306, 7614.162442131361, 7683.423743219281, 4162.146759914211, 4273.380359847804, 4315.3742707307265, 4343.826246950087, 4345.67407444443, 3429.7599168058496, 3489.6684814943046, 3530.505729197914, 3538.663826936437, 3547.5565554305713, 634.4733870887153, 651.771879842061, 660.5046215338496, 661.163628010567, 668.0113432120392, 571.7512881896388, 653.7809833705762, 654.653033892038, 665.4676258992787, 666.3844410688126, 9599.42403455795, 9921.900780992097, 9961.452120133463, 9962.701118966297, 9980.550875210633, 642.6369101457755, 667.3326673326674, 672.428435829541, 676.2610585515363, 700.4046560423599, 4133.697885166163, 4692.436907571045, 4801.567858892717, 4817.940053188293, 4822.0, 95025.52487237658, 97388.51305743426, 99228.51157232648, 99290.0, 100059.0, 45849.08301833944, 76425.6178719114, 77382.61308693534, 83385.0, 86982.69525957116, 73777.26222737704, 73825.63087184637, 75560.62219688867, 75998.86001709978, 76439.0, 10991.670249892515, 10998.560057597606, 11113.277636953895, 11195.832062518906, 11216.439178041228, 31623.20941976469, 45068.0, 46687.83280417881, 49398.0, 50337.741556459914, 58438.831223374444, 65397.67301163464, 66935.33064669486, 68283.0, 70453.29546704673, 11875.524979000744, 12925.28910909909, 12931.418086185957, 13158.539451119164, 13264.071514994244, 13807.286130124983, 14938.178400188093, 15000.849991499945, 15006.849931500981, 16039.358425662842, 13484.325783710972, 13797.517086902311, 13927.58217253484, 13966.205989628806, 14061.789073164115, 13464.326783660974, 13630.727385452434, 14043.438262469386, 14086.859131408555, 14492.710145796815, 10927.398993055458, 12728.427220775273, 12869.420876060787, 12888.742225155633, 13384.72666739303, 10547.419891905713, 11009.449527523751, 11064.668059958214, 11069.279723019841, 11353.262037967506, 7573.734919277799, 9728.367656102331, 9848.0, 10492.632757853438, 10503.792063912748], "value": 7414.401115755964, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1624.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 141.0, 163.0, 119.0, 116.0, 120.0, 116.0, 127.0, 170.0, 122.0], "value": 130.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 117.0, 148.0, 111.0, 142.0, 144.0, 113.0, 144.0, 128.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 272.0, 251.0, 201.0, 197.0, 199.0, 197.0, 191.0, 192.0, 199.0], "value": 210.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 58.0, 87.0, 61.0, 62.0, 69.0, 75.0, 95.0, 83.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 84.0, 74.0, 71.0, 73.0, 82.0, 70.0, 71.0, 81.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 90.0, 97.0, 89.0, 86.0, 90.0, 87.0, 89.0, 84.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 176.0, 131.0, 124.0, 174.0, 172.0, 100.0, 99.0, 107.0, 171.0], "value": 136.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 146.0, 92.0, 148.0, 77.0, 147.0, 146.0, 127.0, 91.0, 142.0], "value": 122.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 64.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 68.0, 76.0, 76.0, 72.0, 70.0, 70.0, 75.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 92.0, 85.0, 98.0, 95.0, 88.0, 88.0, 80.0, 101.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 162.0, 152.0, 154.0, 163.0, 159.0, 153.0, 150.0, 211.0, 190.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 179.0, 244.0, 212.0, 176.0, 175.0, 172.0, 172.0, 228.0, 174.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 67.0, 69.0, 66.0, 69.0, 69.0, 68.0, 68.0], "value": 68.3, "unit": "score"}]}]} 16:58:11 INFO - Return code: 0 16:58:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:58:11 ERROR - # TBPL SUCCESS # 16:58:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:58:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:58:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:58:11 INFO - 2017-01-18 16:58:11.179 screenresolution[1923:23528] starting screenresolution argv=screenresolution get 16:58:11 INFO - 2017-01-18 16:58:11.181 screenresolution[1923:23528] Display 0: 1600x1200x32@60 16:58:11 INFO - 2017-01-18 16:58:11.187 screenresolution[1924:23531] starting screenresolution argv=screenresolution list 16:58:11 INFO - Available Modes on Display 0 16:58:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:58:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:58:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:58:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:58:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:58:11 INFO - Intel Iris: 16:58:11 INFO - Chipset Model: Intel Iris 16:58:11 INFO - Type: GPU 16:58:11 INFO - Bus: Built-In 16:58:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:58:11 INFO - Vendor: Intel (0x8086) 16:58:11 INFO - Device ID: 0x0a2e 16:58:11 INFO - Revision ID: 0x0009 16:58:11 INFO - Displays: 16:58:11 INFO - Display: 16:58:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:58:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:58:11 INFO - Display Serial Number: 1600x1200 60 16:58:11 INFO - Main Display: Yes 16:58:11 INFO - Mirror: Off 16:58:11 INFO - Online: Yes 16:58:11 INFO - Rotation: Supported 16:58:11 INFO - Return code: 0 16:58:11 INFO - Running post-action listener: _resource_record_post_action 16:58:11 INFO - [mozharness: 2017-01-19 00:58:11.424921Z] Finished run-tests step (success) 16:58:11 INFO - Running post-run listener: _resource_record_post_run 16:58:11 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 540672; Write bytes: 555376640; Read time: 4; Write time: 1692 16:58:11 INFO - TinderboxPrint: CPU usage
54.5% 16:58:11 INFO - TinderboxPrint: I/O read bytes / time
540,672 / 4 16:58:11 INFO - TinderboxPrint: I/O write bytes / time
555,376,640 / 1,692 16:58:11 INFO - TinderboxPrint: CPU idle
2,186.7 (45.5%) 16:58:11 INFO - TinderboxPrint: CPU system
967.9 (20.2%) 16:58:11 INFO - TinderboxPrint: CPU user
1,647.1 (34.3%) 16:58:11 INFO - TinderboxPrint: Swap in / out
251,514,880 / 0 16:58:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 242997760; Write bytes: 239243776; Read time: 17568; Write time: 518 16:58:11 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 540672; Write bytes: 298479104; Read time: 4; Write time: 1130 16:58:11 INFO - Running post-run listener: _upload_blobber_files 16:58:11 INFO - Blob upload gear active. 16:58:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:58:11 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 16:58:11 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:58:11 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:58:12 INFO - (blobuploader) - INFO - Open directory for files ... 16:58:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:58:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:58:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:13 INFO - (blobuploader) - INFO - Done attempting. 16:58:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:58:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:14 INFO - (blobuploader) - INFO - Done attempting. 16:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:58:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:15 INFO - (blobuploader) - INFO - Done attempting. 16:58:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:58:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:58:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:17 INFO - (blobuploader) - INFO - Done attempting. 16:58:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:58:17 INFO - Return code: 0 16:58:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:58:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:58:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fbbb7178d0b5fc70cda8b451b0feb1be52d3529f79f757f9676c48a2234ba1b574475684491577fa583ae17fba4a149324a0cde05639d8b8c64a157469948b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cff98bef307bd79d6cc741b7c11d33423e81d1fc45c5406d35f6e2166338bab04d9b353274b0a3846ddb46363a0a6a262ea178842b76b5df1068b46a6d77abe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/714b1fe9b7d5cbc9d749f076d969a74bf654dedcf44d81e220c0264323d986fc71e041b06d0cc4323426c0718bb738e458ed9a3a07a3c0c77ddc7065df545574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ec178beff986dee4ae2968d00f9ecf70574d57442e1b5368320d6d63bbdd7ca9e75b8c16e79cb851c0db329cb550335af31a2a81577dd208f126c4c2cb3d15c8"} 16:58:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:58:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:58:17 INFO - Contents: 16:58:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fbbb7178d0b5fc70cda8b451b0feb1be52d3529f79f757f9676c48a2234ba1b574475684491577fa583ae17fba4a149324a0cde05639d8b8c64a157469948b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cff98bef307bd79d6cc741b7c11d33423e81d1fc45c5406d35f6e2166338bab04d9b353274b0a3846ddb46363a0a6a262ea178842b76b5df1068b46a6d77abe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/714b1fe9b7d5cbc9d749f076d969a74bf654dedcf44d81e220c0264323d986fc71e041b06d0cc4323426c0718bb738e458ed9a3a07a3c0c77ddc7065df545574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ec178beff986dee4ae2968d00f9ecf70574d57442e1b5368320d6d63bbdd7ca9e75b8c16e79cb851c0db329cb550335af31a2a81577dd208f126c4c2cb3d15c8"} 16:58:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:58:17 INFO - Copying logs to upload dir... 16:58:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:58:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.843247 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-18 16:58:17.819380) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 16:58:17.823360) ========= 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.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fbbb7178d0b5fc70cda8b451b0feb1be52d3529f79f757f9676c48a2234ba1b574475684491577fa583ae17fba4a149324a0cde05639d8b8c64a157469948b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cff98bef307bd79d6cc741b7c11d33423e81d1fc45c5406d35f6e2166338bab04d9b353274b0a3846ddb46363a0a6a262ea178842b76b5df1068b46a6d77abe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/714b1fe9b7d5cbc9d749f076d969a74bf654dedcf44d81e220c0264323d986fc71e041b06d0cc4323426c0718bb738e458ed9a3a07a3c0c77ddc7065df545574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ec178beff986dee4ae2968d00f9ecf70574d57442e1b5368320d6d63bbdd7ca9e75b8c16e79cb851c0db329cb550335af31a2a81577dd208f126c4c2cb3d15c8"} build_url:https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/JP_y9JGlRm-hvsjQ6CirTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fbbb7178d0b5fc70cda8b451b0feb1be52d3529f79f757f9676c48a2234ba1b574475684491577fa583ae17fba4a149324a0cde05639d8b8c64a157469948b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cff98bef307bd79d6cc741b7c11d33423e81d1fc45c5406d35f6e2166338bab04d9b353274b0a3846ddb46363a0a6a262ea178842b76b5df1068b46a6d77abe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/714b1fe9b7d5cbc9d749f076d969a74bf654dedcf44d81e220c0264323d986fc71e041b06d0cc4323426c0718bb738e458ed9a3a07a3c0c77ddc7065df545574", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ec178beff986dee4ae2968d00f9ecf70574d57442e1b5368320d6d63bbdd7ca9e75b8c16e79cb851c0db329cb550335af31a2a81577dd208f126c4c2cb3d15c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 16:58:17.857696) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:58:17.858038) ========= 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.BHYDI3UEno/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.6etqApeny6/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:58:17.906612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 16:58:17.906917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 16:58:17.910282) ========= ========= Total master_lag: 0.47 =========