builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0301 starttime: 1484309425.46 results: success (0) buildid: 20170113022616 builduid: 4083f7bd408449a4be70de0343b115da revision: 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.460224) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.460668) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.460939) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.487267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.487615) ========= 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.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-13 04:10:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-01-13 04:10:25 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.664200) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.664559) ========= 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.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.023946 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:25.713944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 04:10:25.714313) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 --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 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-13 04:10:25,813 truncating revision to first 12 chars 2017-01-13 04:10:25,813 Setting DEBUG logging. 2017-01-13 04:10:25,813 attempt 1/10 2017-01-13 04:10:25,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/1d71945a1e74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 04:10:26,448 unpacking tar archive at: graphics-1d71945a1e74/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906206 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 04:10:26.854305) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:26.854623) ========= script_repo_revision: 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:26.855027) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:26.855304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 04:10:26.875855) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-13 04:10:26.876238) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:10:27 INFO - MultiFileLogger online at 20170113 04:10:27 in /builds/slave/test 04:10:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 04:10:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:10:27 INFO - {'append_to_log': False, 04:10:27 INFO - 'base_work_dir': '/builds/slave/test', 04:10:27 INFO - 'blob_upload_branch': 'Graphics', 04:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:10:27 INFO - 'branch': 'Graphics', 04:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 04:10:27 INFO - 'config_files': ('talos/mac_config.py',), 04:10:27 INFO - 'default_actions': ('clobber', 04:10:27 INFO - 'read-buildbot-config', 04:10:27 INFO - 'download-and-extract', 04:10:27 INFO - 'populate-webroot', 04:10:27 INFO - 'create-virtualenv', 04:10:27 INFO - 'install', 04:10:27 INFO - 'run-tests'), 04:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:10:27 INFO - 'download_minidump_stackwalk': True, 04:10:27 INFO - 'download_symbols': 'ondemand', 04:10:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:10:27 INFO - 'tooltool.py': '/tools/tooltool.py', 04:10:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:10:27 INFO - '/tools/misc-python/virtualenv.py')}, 04:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:10:27 INFO - 'installer_path': 'installer.exe', 04:10:27 INFO - 'log_level': 'info', 04:10:27 INFO - 'log_name': 'talos', 04:10:27 INFO - 'log_to_console': True, 04:10:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:10:27 INFO - 'opt_config_files': (), 04:10:27 INFO - 'pip_index': False, 04:10:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:27 INFO - 'cmd': ('bash', 04:10:27 INFO - '-c', 04:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:27 INFO - 'enabled': True, 04:10:27 INFO - 'halt_on_failure': False, 04:10:27 INFO - 'name': 'check_screen_resolution'},), 04:10:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:27 INFO - 'cmd': ('bash', 04:10:27 INFO - '-c', 04:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:27 INFO - 'enabled': True, 04:10:27 INFO - 'halt_on_failure': False, 04:10:27 INFO - 'name': 'check_screen_resolution'},), 04:10:27 INFO - 'run_cmd_checks_enabled': True, 04:10:27 INFO - 'sps_profile': False, 04:10:27 INFO - 'sps_profile_interval': 0, 04:10:27 INFO - 'suite': 'dromaeojs', 04:10:27 INFO - 'system_bits': '32', 04:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:10:27 INFO - 'title': 't-yosemite-r7-0301', 04:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:10:27 INFO - 'use_talos_json': True, 04:10:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:10:27 INFO - 'work_dir': 'build'} 04:10:27 INFO - [mozharness: 2017-01-13 12:10:27.082516Z] Running clobber step. 04:10:27 INFO - Running pre-action listener: _resource_record_pre_action 04:10:27 INFO - Running main action method: clobber 04:10:27 INFO - rmtree: /builds/slave/test/build 04:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:10:29 INFO - Running post-action listener: _resource_record_post_action 04:10:29 INFO - [mozharness: 2017-01-13 12:10:29.341012Z] Finished clobber step (success) 04:10:29 INFO - [mozharness: 2017-01-13 12:10:29.341180Z] Running read-buildbot-config step. 04:10:29 INFO - Running pre-action listener: _resource_record_pre_action 04:10:29 INFO - Running main action method: read_buildbot_config 04:10:29 INFO - Using buildbot properties: 04:10:29 INFO - { 04:10:29 INFO - "project": "", 04:10:29 INFO - "product": "firefox", 04:10:29 INFO - "script_repo_revision": "default", 04:10:29 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 04:10:29 INFO - "repository": "", 04:10:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 04:10:29 INFO - "buildid": "20170113022616", 04:10:29 INFO - "pgo_build": "False", 04:10:29 INFO - "basedir": "/builds/slave/test", 04:10:29 INFO - "buildnumber": 9, 04:10:29 INFO - "slavename": "t-yosemite-r7-0301", 04:10:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:10:29 INFO - "platform": "yosemite_r7", 04:10:29 INFO - "scheduler": "tests-graphics-macosx64-talos", 04:10:29 INFO - "branch": "graphics", 04:10:29 INFO - "repo_path": "projects/graphics", 04:10:29 INFO - "revision": "1d71945a1e748f114cd71d0aa5e0ed1d88bf8055", 04:10:29 INFO - "stage_platform": "macosx64", 04:10:29 INFO - "builduid": "4083f7bd408449a4be70de0343b115da", 04:10:29 INFO - "slavebuilddir": "test" 04:10:29 INFO - } 04:10:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:10:29 INFO - Running post-action listener: _resource_record_post_action 04:10:29 INFO - [mozharness: 2017-01-13 12:10:29.344655Z] Finished read-buildbot-config step (success) 04:10:29 INFO - [mozharness: 2017-01-13 12:10:29.344787Z] Running download-and-extract step. 04:10:29 INFO - Running pre-action listener: _resource_record_pre_action 04:10:29 INFO - Running main action method: download_and_extract 04:10:29 INFO - mkdir: /builds/slave/test/build/tests 04:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:29 INFO - trying https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:10:29 INFO - Downloading https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:10:30 INFO - Downloaded 1199 bytes. 04:10:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:10:30 INFO - Using the following test package requirements: 04:10:30 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:10:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:10:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:10:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'jsshell-mac.zip'], 04:10:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:10:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:10:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:10:30 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:10:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:10:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:10:30 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:10:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:10:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:10:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:10:30 INFO - Fetch https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:10:31 INFO - Expected file size: 16314160 04:10:31 INFO - Obtained file size: 16314160 04:10:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:10:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:10:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:10:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:10:38 INFO - Expected file size: 16314160 04:10:38 INFO - Obtained file size: 16314160 04:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:10:46 INFO - Expected file size: 14403532 04:10:46 INFO - Obtained file size: 14403532 04:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:47 INFO - trying https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:10:47 INFO - Downloading https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:10:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:10:49 INFO - Downloaded 70113718 bytes. 04:10:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:10:49 INFO - mkdir: /builds/slave/test/properties 04:10:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:10:49 INFO - Writing to file /builds/slave/test/properties/build_url 04:10:49 INFO - Contents: 04:10:49 INFO - build_url:https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:10:50 INFO - Running post-action listener: _resource_record_post_action 04:10:50 INFO - Running post-action listener: set_extra_try_arguments 04:10:50 INFO - [mozharness: 2017-01-13 12:10:50.383942Z] Finished download-and-extract step (success) 04:10:50 INFO - [mozharness: 2017-01-13 12:10:50.384092Z] Running populate-webroot step. 04:10:50 INFO - Running pre-action listener: _resource_record_pre_action 04:10:50 INFO - Running main action method: populate_webroot 04:10:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:10:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:10:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:10:50 INFO - '--tppagecycles', 04:10:50 INFO - '7'], 04:10:50 INFO - 'tests': ['tsvgm']}}, 04:10:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:10:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:10:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:10:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:10:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['damp', 'tps']}, 04:10:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'tests': ['damp', 'tps']}, 04:10:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['dromaeo_dom']}, 04:10:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:10:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['basic_compositor_video']}, 04:10:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:10:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'other-e10s': {'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['a11yr', 04:10:50 INFO - 'ts_paint', 04:10:50 INFO - 'tpaint', 04:10:50 INFO - 'sessionrestore', 04:10:50 INFO - 'sessionrestore_no_auto_restore', 04:10:50 INFO - 'tabpaint']}, 04:10:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['tsvgx', 04:10:50 INFO - 'tsvgr_opacity', 04:10:50 INFO - 'tart', 04:10:50 INFO - 'tscrollx', 04:10:50 INFO - 'cart', 04:10:50 INFO - 'tsvg_static']}, 04:10:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:10:50 INFO - 'tsvgr_opacity', 04:10:50 INFO - 'tart', 04:10:50 INFO - 'tscrollx', 04:10:50 INFO - 'cart', 04:10:50 INFO - 'tsvg_static']}, 04:10:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'talos_options': ['--disable-e10s'], 04:10:50 INFO - 'tests': ['tp5o']}, 04:10:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:10:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'talos_options': ['--disable-e10s', 04:10:50 INFO - '--xperf_path', 04:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:50 INFO - 'tests': ['tp5n']}, 04:10:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:10:50 INFO - 'talos_options': ['--xperf_path', 04:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:50 INFO - 'tests': ['tp5n']}}, 04:10:50 INFO - 'talos.zip': {'path': '', 04:10:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:10:50 INFO - Running post-action listener: _resource_record_post_action 04:10:50 INFO - [mozharness: 2017-01-13 12:10:50.401813Z] Finished populate-webroot step (success) 04:10:50 INFO - [mozharness: 2017-01-13 12:10:50.401940Z] Running create-virtualenv step. 04:10:50 INFO - Running pre-action listener: _resource_record_pre_action 04:10:50 INFO - Running main action method: create_virtualenv 04:10:50 INFO - Creating virtualenv /builds/slave/test/build/venv 04:10:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:10:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:10:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:50 INFO - Using real prefix '/tools/python27' 04:10:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:10:51 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:54 INFO - Installing pip.................done. 04:10:54 INFO - Return code: 0 04:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:10:54 INFO - Reading from file tmpfile_stdout 04:10:54 INFO - Output received: 04:10:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:10:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:54 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:54 INFO - 'HOME': '/Users/cltbld', 04:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:54 INFO - 'LOGNAME': 'cltbld', 04:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:54 INFO - 'MOZ_NO_REMOTE': '1', 04:10:54 INFO - 'NO_EM_RESTART': '1', 04:10:54 INFO - 'PAGER': '/bin/cat', 04:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:54 INFO - 'PWD': '/builds/slave/test', 04:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:54 INFO - 'SHELL': '/bin/bash', 04:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:10:54 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:54 INFO - 'USER': 'cltbld', 04:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:54 INFO - 'XPC_FLAGS': '0x0', 04:10:54 INFO - 'XPC_SERVICE_NAME': '0', 04:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:10:54 INFO - Cleaning up... 04:10:54 INFO - Return code: 0 04:10:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:54 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:54 INFO - 'HOME': '/Users/cltbld', 04:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:54 INFO - 'LOGNAME': 'cltbld', 04:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:54 INFO - 'MOZ_NO_REMOTE': '1', 04:10:54 INFO - 'NO_EM_RESTART': '1', 04:10:54 INFO - 'PAGER': '/bin/cat', 04:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:54 INFO - 'PWD': '/builds/slave/test', 04:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:54 INFO - 'SHELL': '/bin/bash', 04:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:10:54 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:54 INFO - 'USER': 'cltbld', 04:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:54 INFO - 'XPC_FLAGS': '0x0', 04:10:54 INFO - 'XPC_SERVICE_NAME': '0', 04:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:55 INFO - Downloading/unpacking psutil>=3.1.1 04:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:10:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:58 INFO - Installing collected packages: psutil 04:10:58 INFO - Running setup.py install for psutil 04:10:58 INFO - building 'psutil._psutil_osx' extension 04:10:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:10:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:10:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:10:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:10:59 INFO - building 'psutil._psutil_posix' extension 04:10:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:10:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:10:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:10:59 INFO - ^ 04:10:59 INFO - 1 warning generated. 04:10:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:10:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:59 INFO - Successfully installed psutil 04:10:59 INFO - Cleaning up... 04:10:59 INFO - Return code: 0 04:10:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:59 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:59 INFO - 'HOME': '/Users/cltbld', 04:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:59 INFO - 'LOGNAME': 'cltbld', 04:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:59 INFO - 'MOZ_NO_REMOTE': '1', 04:10:59 INFO - 'NO_EM_RESTART': '1', 04:10:59 INFO - 'PAGER': '/bin/cat', 04:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:59 INFO - 'PWD': '/builds/slave/test', 04:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:59 INFO - 'SHELL': '/bin/bash', 04:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:10:59 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:59 INFO - 'USER': 'cltbld', 04:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:59 INFO - 'XPC_FLAGS': '0x0', 04:10:59 INFO - 'XPC_SERVICE_NAME': '0', 04:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:11:02 INFO - Installing collected packages: mozsystemmonitor 04:11:02 INFO - Running setup.py install for mozsystemmonitor 04:11:02 INFO - Successfully installed mozsystemmonitor 04:11:02 INFO - Cleaning up... 04:11:03 INFO - Return code: 0 04:11:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:03 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:03 INFO - 'HOME': '/Users/cltbld', 04:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:03 INFO - 'LOGNAME': 'cltbld', 04:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:03 INFO - 'MOZ_NO_REMOTE': '1', 04:11:03 INFO - 'NO_EM_RESTART': '1', 04:11:03 INFO - 'PAGER': '/bin/cat', 04:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:03 INFO - 'PWD': '/builds/slave/test', 04:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:03 INFO - 'SHELL': '/bin/bash', 04:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:03 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:03 INFO - 'USER': 'cltbld', 04:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:03 INFO - 'XPC_FLAGS': '0x0', 04:11:03 INFO - 'XPC_SERVICE_NAME': '0', 04:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:03 INFO - Downloading/unpacking jsonschema==2.5.1 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:11:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:06 INFO - Downloading functools32-3.2.3-2.tar.gz 04:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:11:06 INFO - warning: no files found matching '*.txt' 04:11:06 INFO - no previously-included directories found matching 'build' 04:11:06 INFO - no previously-included directories found matching 'dist' 04:11:06 INFO - no previously-included directories found matching '.git*' 04:11:06 INFO - Installing collected packages: jsonschema, functools32 04:11:06 INFO - Running setup.py install for functools32 04:11:06 INFO - warning: no files found matching '*.txt' 04:11:06 INFO - no previously-included directories found matching 'build' 04:11:06 INFO - no previously-included directories found matching 'dist' 04:11:06 INFO - no previously-included directories found matching '.git*' 04:11:06 INFO - Successfully installed jsonschema functools32 04:11:06 INFO - Cleaning up... 04:11:06 INFO - Return code: 0 04:11:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:06 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:06 INFO - 'HOME': '/Users/cltbld', 04:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:06 INFO - 'LOGNAME': 'cltbld', 04:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:06 INFO - 'MOZ_NO_REMOTE': '1', 04:11:06 INFO - 'NO_EM_RESTART': '1', 04:11:06 INFO - 'PAGER': '/bin/cat', 04:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:06 INFO - 'PWD': '/builds/slave/test', 04:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:06 INFO - 'SHELL': '/bin/bash', 04:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:06 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:06 INFO - 'USER': 'cltbld', 04:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:06 INFO - 'XPC_FLAGS': '0x0', 04:11:06 INFO - 'XPC_SERVICE_NAME': '0', 04:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:11:06 INFO - Cleaning up... 04:11:07 INFO - Return code: 0 04:11:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:07 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:07 INFO - 'HOME': '/Users/cltbld', 04:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:07 INFO - 'LOGNAME': 'cltbld', 04:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:07 INFO - 'MOZ_NO_REMOTE': '1', 04:11:07 INFO - 'NO_EM_RESTART': '1', 04:11:07 INFO - 'PAGER': '/bin/cat', 04:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:07 INFO - 'PWD': '/builds/slave/test', 04:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:07 INFO - 'SHELL': '/bin/bash', 04:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:07 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:07 INFO - 'USER': 'cltbld', 04:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:07 INFO - 'XPC_FLAGS': '0x0', 04:11:07 INFO - 'XPC_SERVICE_NAME': '0', 04:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:09 INFO - Downloading blobuploader-1.2.4.tar.gz 04:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:11:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:11:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:11 INFO - Downloading docopt-0.6.1.tar.gz 04:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:11:11 INFO - Installing collected packages: blobuploader, requests, docopt 04:11:11 INFO - Running setup.py install for blobuploader 04:11:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:11:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:11:11 INFO - Running setup.py install for requests 04:11:11 INFO - Running setup.py install for docopt 04:11:12 INFO - Successfully installed blobuploader requests docopt 04:11:12 INFO - Cleaning up... 04:11:12 INFO - Return code: 0 04:11:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:12 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:12 INFO - 'HOME': '/Users/cltbld', 04:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:12 INFO - 'LOGNAME': 'cltbld', 04:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:12 INFO - 'MOZ_NO_REMOTE': '1', 04:11:12 INFO - 'NO_EM_RESTART': '1', 04:11:12 INFO - 'PAGER': '/bin/cat', 04:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:12 INFO - 'PWD': '/builds/slave/test', 04:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:12 INFO - 'SHELL': '/bin/bash', 04:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:12 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:12 INFO - 'USER': 'cltbld', 04:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:12 INFO - 'XPC_FLAGS': '0x0', 04:11:12 INFO - 'XPC_SERVICE_NAME': '0', 04:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-efrZUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-BEHKUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Dr6AQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-trOeL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-z8lha_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:12 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-E5yurI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ynqsdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-sMkko7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-QDZ7XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-R1Qy8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-CqWS6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-rh1qxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-N2JhJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-DxKvs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-x3FfRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dySQSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-HwHmbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:11:14 INFO - Running setup.py install for manifestparser 04:11:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:11:14 INFO - Running setup.py install for mozcrash 04:11:14 INFO - Running setup.py install for mozdebug 04:11:14 INFO - Running setup.py install for mozdevice 04:11:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:11:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:11:14 INFO - Running setup.py install for mozfile 04:11:14 INFO - Running setup.py install for mozhttpd 04:11:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Running setup.py install for mozinfo 04:11:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Running setup.py install for mozInstall 04:11:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Running setup.py install for mozleak 04:11:15 INFO - Running setup.py install for mozlog 04:11:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Running setup.py install for moznetwork 04:11:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:11:15 INFO - Running setup.py install for mozprocess 04:11:15 INFO - Running setup.py install for mozprofile 04:11:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:11:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:11:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:11:16 INFO - Running setup.py install for mozrunner 04:11:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:11:16 INFO - Running setup.py install for mozscreenshot 04:11:16 INFO - Running setup.py install for moztest 04:11:16 INFO - Running setup.py install for mozversion 04:11:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:11:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:11:16 INFO - Cleaning up... 04:11:16 INFO - Return code: 0 04:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11: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')]} 04:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11: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')]} 04:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:16 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:16 INFO - 'HOME': '/Users/cltbld', 04:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:16 INFO - 'LOGNAME': 'cltbld', 04:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:16 INFO - 'MOZ_NO_REMOTE': '1', 04:11:16 INFO - 'NO_EM_RESTART': '1', 04:11:16 INFO - 'PAGER': '/bin/cat', 04:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:16 INFO - 'PWD': '/builds/slave/test', 04:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:16 INFO - 'SHELL': '/bin/bash', 04:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:16 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:16 INFO - 'USER': 'cltbld', 04:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:16 INFO - 'XPC_FLAGS': '0x0', 04:11:16 INFO - 'XPC_SERVICE_NAME': '0', 04:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-wJVEFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-0Ez3oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-vESsWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pa3B2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-y3Mplm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-N3bvrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-MALVqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-OBKguR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dNWNck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:17 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-v9DR4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-UfCD5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-DACVoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-5OOVGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-VctzrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-vJBuPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-sge22B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:18 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-4Kmxwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:21 INFO - Downloading blessings-1.6.tar.gz 04:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:11:21 INFO - Installing collected packages: blessings 04:11:21 INFO - Running setup.py install for blessings 04:11:21 INFO - Successfully installed blessings 04:11:21 INFO - Cleaning up... 04:11:21 INFO - Return code: 0 04:11:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:21 INFO - Reading from file tmpfile_stdout 04:11:22 INFO - Current package versions: 04:11:22 INFO - blessings == 1.6 04:11:22 INFO - blobuploader == 1.2.4 04:11:22 INFO - docopt == 0.6.1 04:11:22 INFO - functools32 == 3.2.3-2 04:11:22 INFO - jsonschema == 2.5.1 04:11:22 INFO - manifestparser == 1.1 04:11:22 INFO - mozInstall == 1.12 04:11:22 INFO - mozcrash == 1.0 04:11:22 INFO - mozdebug == 0.1 04:11:22 INFO - mozdevice == 0.49 04:11:22 INFO - mozfile == 1.2 04:11:22 INFO - mozhttpd == 0.7 04:11:22 INFO - mozinfo == 0.9 04:11:22 INFO - mozleak == 0.1 04:11:22 INFO - mozlog == 3.4 04:11:22 INFO - moznetwork == 0.27 04:11:22 INFO - mozprocess == 0.24 04:11:22 INFO - mozprofile == 0.28 04:11:22 INFO - mozrunner == 6.13 04:11:22 INFO - mozscreenshot == 0.1 04:11:22 INFO - mozsystemmonitor == 0.3 04:11:22 INFO - moztest == 0.8 04:11:22 INFO - mozversion == 1.4 04:11:22 INFO - psutil == 3.1.1 04:11:22 INFO - requests == 1.2.3 04:11:22 INFO - wsgiref == 0.1.2 04:11:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:23 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 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:23 INFO - 'HOME': '/Users/cltbld', 04:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:23 INFO - 'LOGNAME': 'cltbld', 04:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:23 INFO - 'MOZ_NO_REMOTE': '1', 04:11:23 INFO - 'NO_EM_RESTART': '1', 04:11:23 INFO - 'PAGER': '/bin/cat', 04:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:23 INFO - 'PWD': '/builds/slave/test', 04:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:23 INFO - 'SHELL': '/bin/bash', 04:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:23 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:23 INFO - 'USER': 'cltbld', 04:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:23 INFO - 'XPC_FLAGS': '0x0', 04:11:23 INFO - 'XPC_SERVICE_NAME': '0', 04:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:11:23 INFO - Cleaning up... 04:11:23 INFO - Return code: 0 04:11:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10318ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff40a54cac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, '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.W4tVLvEAur/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.B4oqMxioQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:23 INFO - 'HOME': '/Users/cltbld', 04:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:23 INFO - 'LOGNAME': 'cltbld', 04:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:23 INFO - 'MOZ_NO_REMOTE': '1', 04:11:23 INFO - 'NO_EM_RESTART': '1', 04:11:23 INFO - 'PAGER': '/bin/cat', 04:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:23 INFO - 'PWD': '/builds/slave/test', 04:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:23 INFO - 'SHELL': '/bin/bash', 04:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:23 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:23 INFO - 'USER': 'cltbld', 04:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:23 INFO - 'XPC_FLAGS': '0x0', 04:11:23 INFO - 'XPC_SERVICE_NAME': '0', 04:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:11:23 INFO - Cleaning up... 04:11:23 INFO - Return code: 0 04:11:23 INFO - Running post-action listener: _resource_record_post_action 04:11:23 INFO - Running post-action listener: _start_resource_monitoring 04:11:23 INFO - Starting resource monitoring. 04:11:23 INFO - [mozharness: 2017-01-13 12:11:23.995594Z] Finished create-virtualenv step (success) 04:11:23 INFO - [mozharness: 2017-01-13 12:11:23.996620Z] Running install step. 04:11:23 INFO - Running pre-action listener: _resource_record_pre_action 04:11:23 INFO - Running main action method: install 04:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:24 INFO - Reading from file tmpfile_stdout 04:11:24 INFO - Detecting whether we're running mozinstall >=1.0... 04:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:11:24 INFO - Reading from file tmpfile_stdout 04:11:24 INFO - Output received: 04:11:24 INFO - Usage: mozinstall [options] installer 04:11:24 INFO - Options: 04:11:24 INFO - -h, --help show this help message and exit 04:11:24 INFO - -d DEST, --destination=DEST 04:11:24 INFO - Directory to install application into. [default: 04:11:24 INFO - "/builds/slave/test"] 04:11:24 INFO - --app=APP Application being installed. [default: firefox] 04:11:24 INFO - mkdir: /builds/slave/test/build/application 04:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:11:46 INFO - Reading from file tmpfile_stdout 04:11:46 INFO - Output received: 04:11:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:11:46 INFO - Running post-action listener: _resource_record_post_action 04:11:46 INFO - [mozharness: 2017-01-13 12:11:46.249628Z] Finished install step (success) 04:11:46 INFO - [mozharness: 2017-01-13 12:11:46.249776Z] Running run-tests step. 04:11:46 INFO - Running pre-action listener: _resource_record_pre_action 04:11:46 INFO - Running main action method: run_tests 04:11:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:11:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:11:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:11:46 INFO - 2017-01-13 04:11:46.262 screenresolution[1620:7326] starting screenresolution argv=screenresolution get 04:11:46 INFO - 2017-01-13 04:11:46.264 screenresolution[1620:7326] Display 0: 1600x1200x32@60 04:11:46 INFO - 2017-01-13 04:11:46.269 screenresolution[1621:7329] starting screenresolution argv=screenresolution list 04:11:46 INFO - Available Modes on Display 0 04:11:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:11:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:11:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:11:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:11:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:11:46 INFO - Intel Iris: 04:11:46 INFO - Chipset Model: Intel Iris 04:11:46 INFO - Type: GPU 04:11:46 INFO - Bus: Built-In 04:11:46 INFO - VRAM (Dynamic, Max): 1536 MB 04:11:46 INFO - Vendor: Intel (0x8086) 04:11:46 INFO - Device ID: 0x0a2e 04:11:46 INFO - Revision ID: 0x0009 04:11:46 INFO - Displays: 04:11:46 INFO - Display: 04:11:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:11:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:11:46 INFO - Display Serial Number: 1600x1200 60 04:11:46 INFO - Main Display: Yes 04:11:46 INFO - Mirror: Off 04:11:46 INFO - Online: Yes 04:11:46 INFO - Rotation: Supported 04:11:46 INFO - Return code: 0 04:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:11:46 INFO - Python 2.7.3 04:11:46 INFO - Return code: 0 04:11:46 INFO - grabbing minidump binary from tooltool 04:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:46 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 0x102f68d30>, '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 0x102e8fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f5dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:11:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:11:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:11:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:11:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:11:46 INFO - Return code: 0 04:11:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:11:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:11:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:11:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:11:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W4tVLvEAur/Render', 04:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:46 INFO - 'HOME': '/Users/cltbld', 04:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:46 INFO - 'LOGNAME': 'cltbld', 04:11:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:11:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:46 INFO - 'MOZ_NO_REMOTE': '1', 04:11:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:11:46 INFO - 'NO_EM_RESTART': '1', 04:11:46 INFO - 'PAGER': '/bin/cat', 04:11:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:46 INFO - 'PWD': '/builds/slave/test', 04:11:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:46 INFO - 'SHELL': '/bin/bash', 04:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4oqMxioQc/Listeners', 04:11:46 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:46 INFO - 'USER': 'cltbld', 04:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:46 INFO - 'XPC_FLAGS': '0x0', 04:11:46 INFO - 'XPC_SERVICE_NAME': '0', 04:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:11:46 INFO - mozversion application_buildid: 20170113022616 04:11:46 INFO - mozversion application_changeset: 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 04:11:46 INFO - mozversion application_display_name: Nightly 04:11:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:11:46 INFO - mozversion application_name: Firefox 04:11:46 INFO - mozversion application_remotingname: firefox 04:11:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 04:11:46 INFO - mozversion application_vendor: Mozilla 04:11:46 INFO - mozversion application_version: 53.0a1 04:11:46 INFO - mozversion platform_buildid: 20170113022616 04:11:46 INFO - mozversion platform_changeset: 1d71945a1e748f114cd71d0aa5e0ed1d88bf8055 04:11:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 04:11:46 INFO - mozversion platform_version: 53.0a1 04:11:46 INFO - using testdate: 1484309506 04:11:46 INFO - actual date: 1484309506 04:11:46 INFO - starting webserver on 'localhost:49243' 04:11:46 INFO - SUITE-START | Running 2 tests 04:11:46 INFO - TEST-START | dromaeo_css 04:11:46 INFO - operating with platform_type : mac_ 04:11:46 INFO - Initialising browser for dromaeo_css test... 04:11:46 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpmfC7Mc/profile http://localhost:49243/getInfo.html) 04:11:49 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 04:11:49 INFO - PROCESS | 1631 | colorDepth:24 04:11:49 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 04:11:49 INFO - PROCESS | 1631 | __metrics 04:11:49 INFO - PROCESS | 1631 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:11:49 INFO - PROCESS | 1631 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:11:50 INFO - TEST-INFO | 1631: exit 0 04:11:50 INFO - Browser initialized. 04:11:50 INFO - Running cycle 1/1 for dromaeo_css test... 04:11:50 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpmfC7Mc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:01 INFO - PROCESS | 1633 | RSS: Main: 235622400 04:12:01 INFO - PROCESS | 1633 | 04:15:05 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:15:07 INFO - PROCESS | 1633 | RSS: Main: 402862080 04:15:07 INFO - PROCESS | 1633 | 04:16:50 INFO - PROCESS | 1633 | ************************* 04:16:50 INFO - PROCESS | 1633 | A coding exception was thrown and uncaught in a Task. 04:16:50 INFO - PROCESS | 1633 | 04:16:50 INFO - PROCESS | 1633 | Full message: ReferenceError: fetch is not defined 04:16:50 INFO - PROCESS | 1633 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:16:50 INFO - PROCESS | 1633 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:16:50 INFO - PROCESS | 1633 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:16:50 INFO - PROCESS | 1633 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:16:50 INFO - PROCESS | 1633 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:16:50 INFO - PROCESS | 1633 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:16:50 INFO - PROCESS | 1633 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:16:50 INFO - PROCESS | 1633 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:16:50 INFO - PROCESS | 1633 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:16:50 INFO - PROCESS | 1633 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:16:50 INFO - PROCESS | 1633 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:16:50 INFO - PROCESS | 1633 | 04:16:50 INFO - PROCESS | 1633 | ************************* 04:18:10 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 04:18:10 INFO - PROCESS | 1633 | RSS: Main: 378511360 04:18:10 INFO - PROCESS | 1633 | 04:21:13 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 04:21:13 INFO - PROCESS | 1633 | RSS: Main: 339795968 04:21:13 INFO - PROCESS | 1633 | 04:24:16 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 04:24:16 INFO - PROCESS | 1633 | RSS: Main: 344997888 04:24:16 INFO - PROCESS | 1633 | 04:27:20 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 04:27:20 INFO - PROCESS | 1633 | RSS: Main: 327368704 04:27:20 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 04:30:24 INFO - PROCESS | 1633 | RSS: Main: 396623872 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | __start_tp_report 04:30:24 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 04:30:24 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 04:30:24 INFO - PROCESS | 1633 | |i|pagename|runs| 04:30:24 INFO - PROCESS | 1633 | |0;dojo.html;3257.6416594174643;3258.1203075169706;3283.507473878918;3295.0279667498085;3347.4309367407536;5429.239906413095;5494.780208791649;5495.20319553665;5523.25436066131;5534.833954981357;5287.180487024508;5498.257735205746;5565.359983601887;5616.045272303708;5655.236543066674;4111.794410279489;5027.170516864713;5051.1413059779925;5074.467180946003;5107.131787596118;4313.719608225455;5079.746012699368;5181.222816577525;5199.636025478199;5250.553702935236;5175.01674681813;5177.767000484987;5196.58427325815;5201.895962080737;5204.817831375903;4992.375953005874;5042.1428357179;5053.36832895888;5057.165567681328;5101.51535604117;5059.494050594948;5090.669106508065;5095.949040509585;5136.948630513722;5137.845864624067;5533.418991005967;5576.024195765765;5576.052071147915;5592.692401917893;5621.7189140542805;5889.204957330749;5918.526517878559;5944.524438044946;6058.848528786772;6106.664133472657;6094.573379863411;6115.218884034779;6188.721507532172;6200.689965501707;6231.376862313778;5596.944030559724;5669.4047125051275;5677.630953987978;5684.687342196219;5706.572007099443;6106.430331452215;6269.341719119427;6289.371062893765;6293.118963345135;6297.874042519124;5862.560307976833;5892.793752218652;5897.380775018647;5956.821295361146;6029.969850150721;5951.166836642873;6122.66325352101;6146.7233974471255;6150.108234306001;6157.415045570664;4907.803687852446;5899.557533185029;5979.073243647242;6009.218801555857;6066.059760737093;6129.8898215898025;6133.916185115316;6146.170267013986;6146.754129834846;6148.415900489446;4780.378550788375;4824.324594556765;4831.347768051268;4850.466448690641;4869.561739443397;4337.609615134653;4358.585934336233;4376.081022985209;4399.780010999437;4435.423394958635;5621.606487545832;5641;5646.6894320811925;5668.461496157858;5676.716164191774;5895.882082358415;5925.281668316226;5979.103134529768;6230.283517395467;6252.187215661935;5533.944660553423;5540.141278101901;5579.7489112990015;5591.9720401397735;5603.019471592455;5312.2297266896085;5370.3287089113865;5372.973135134299;5379.381371142291;5416.350037995466;6191.9690401547705;6192.195014648157;6195.287541932736;6198.721057552511;6204.5967012144065;5112.539871411442;6158.199434073542;6194.442500175006;6197.752089916353;6247.656378899204;6114.631938157225;6154.476869466145;6271.498280137669;6314.747410103544;6324.841878953063;6097.085437184458;6097.4817140543555;6113.816585502442;6116.5718399711595;6141.9385806143155;6099.207103076572;6161.815145545641;6184.103305020837;6187.443130118311;6198.442140207403;5780.479756821906;5848.53211743051;5850.707464626837;5938.584299099021;5945.375735547878;5763.654180749001;5902.498287645427;5925.081612350093;5946.524278057832;5954.19618351517;5737.770489180386;5807.883842323215;5814.186013958134;5830.766769329349;5901.852453688693;5063.696178229318;6007.038873780174;6011.969940150271;6017.699115044318;6022.2171117754415;5836.678982655825;5839.854003649943;5841.474267315962;5842.737076831638;5849.795257165979 04:30:24 INFO - PROCESS | 1633 | |1;ext.html;444.26030658952845;505.4490605240287;519.2989464223298;528.6246764796994;536.2787051415844;30550.694493055064;40141.79929100354;41592.16815663685;41690.79154604227;42088.78955605225;43142;43360.447153048866;43443;43467.34798978018;43509.782451087776;9191.172794448516;9400.670976515823;9402.153806157445;9414.293927955396;9420.905790942106;8406.327493800512;8602.397832151752;11130.998210161084;11373;11405.14461415393;11330.376829274386;11370.71573210668;11379.829302560427;11404.657860264206;11433.256838305526;11314.603988860392;11375;11387.373694446802;11408.657740267763;11410.315381077162;8293.751187464386;8536.231739143444;11007.449627518592;11236.606718764804;11250.437478126143;40037.39943900844;40369.79815100935;40378.394324085166;40448.79775601133;40452.797736011424;4071.389291606253;4079.740380157637;4094.447249621293;4110.588941105895;4121.093359460914;30375.887581309984;30631;31284.843575781975;31308.373832523223;31429;3168.1604374840686;3268.4116858965376;3283.326917981808;3322.4684050552037;3325.96674033259;314761;317152;418351.999999997;423264;423645;50024.74987625112;59665.403345965984;59802.70098649479;59853.10220346698;60035.399646003854;62964;62977.370226298066;63031;63114.68442657757;63176.68411657912;15766.76349854753;16004.119773412574;16004.199790010452;16005.839941600434;16046.358145674043;11276.605318813803;12284.447199876027;12378.504859805507;12392.380380980914;12406.81389779154;14635.414583416758;15092;15130;15146.242687865562;15152.48475152498;5763.8559036024435;6720.731170753213;6761.526693131532;6781.287964763727;6813.092334146447;881.1056777370974;888.3426264564176;902.1429641840147;908.8409528332647;925.5696101312922;28734.568981465294;30014;30058.699413006027;30251.705951458847;30872.0738377849;934.6354921580589;1005.9547320370601;1048.4128887822844;1053.4153544782719;1076.3864597179534;13781.379837907318;14377.78433323501;14408.279586020657;14623.341949612293;14730.55808325752;592722;597914;598013;598827;599091;42852;51422.74288628533;51434;51444.22833657498;51563.48436515662;1248.063952035973;1431.1842249917656;1451.8765904897991;1455.716135353596;1457.679310551677;20993.89503052536;25075;25996;27069.1879243623;27069.72930270751;44056.11887762163;45713.542864570925;45811.31283030824;45824.08351833012;46018.53981460143;506516;508525;509512;509537;509566;23937;24241.39396515101;24318.027278909354;44127.77936110299;45464.77267613641;48429;49832.750836245585;50039;50400.49599503958;50606.49393506165;38755.80622096871;40292.79853600713;41047.794761026;41128.794356028026;42283;40561;40876.59123408728;41014;41047.794761026;42071;32872.096859658785;39675.404868927566;40365.79817100896;40486.797566011985;42803.92990175271;31675.841620791747;36019;36122.277554448236;36478.81760591286;36573.45139822958;25926.87036564805;26204;26452.8677356612;26767;26870.865645671645 04:30:24 INFO - PROCESS | 1633 | |2;jquery.html;370.1246551904746;390.4163275902524;395.59056376650165;400.1696479804406;422.9555896630854;14637.024001759759;15974.92012539937;19050.523736906584;21295.935203239853;21321.36035918925;15809.269895387846;21560.02979865906;21950.45123871904;22011.258086125395;22320.21879234227;5333.4399888011785;5494.752736126884;5698.943010569903;5870.471657550819;5968.373320801296;5981.88036239275;6020.518358531307;6024.578279520435;6036.275646922375;6036.849078773043;3850.8267127979307;3888.5333759948844;4054.6350828425434;4078.021274894019;6472.255690595571;6505.739770409178;6521.902171467432;6525.314841941623;6531.575447595891;6556.409923106896;5611.102223644238;5751.597388182829;5795.1017592273265;5806.303243610751;5808.6224395414165;13696.315184240848;13867.930660346734;13894.583162505141;20724.756514609173;21028.579428411347;2540.733223011576;2563.4104156044214;2585.3924327782956;2586.3663402466327;2616.6467526883816;8643.65425382984;10170.796584068277;10250.38497690141;10330.12193963514;10546.894531054668;1767.3372485318005;1803.2606631281112;1832.065646520271;1843.793570539007;1845.501714537081;119836.40081799623;128293.41093203284;129795;131570.34214828865;132193;11426.600068997544;11787.882121178678;11813.173077884465;11836.452937758635;11839.408029598486;15599.142047187286;15624.76562851558;15630.3747850087;15633.921830390775;15647.765283520528;8467.618957146944;8630.921134858143;8647.610857511427;8780.912190878138;8800.60397282111;7643.394887073666;7772.455928085093;8362.498250105013;8407.873881891777;8556.27271681914;4503.887402814956;4697.4832768395445;4790.520947905182;4791.329213910055;4895.951040489621;1829.2408650410198;2028.7869773673847;2037.94905127373;2041.5610643711673;2042.4375352855077;968.4092703450889;989.6486747204783;993.7068564773409;997.765525101615;1038.2524582300732;20946.476338091365;21238.89380553087;21532.78467215339;21540.892295538422;21621.24325648595;1028.7428142964259;1032.3135115148493;1033.8862725100232;1075.1237741240864;1079.9082078023298;10921.945390272998;11153.609623663295;11203.327800331843;11203.383813890314;11219.71950701239;504974;576453;582641;591295;715216;118348.40825795816;121215.6662767095;152126;152463.71304430443;152547.2372638152;1029.1406675425953;1069.2408390043156;1088.455772113943;1089.6349722842936;1090.7000574841823;19531.804681953567;19704.76347585276;19846.702299465807;20025.799742002393;20050.899745501178;47917.76041119772;50441.74779126081;58204;58571.707141465726;59033.70483147557;87306;87801;88512.59130382603;88674;90924;8261.586920653823;8318.251357377865;8343.791405214919;8398.160183981552;8625.482471051757;12180.817287740514;13048.151870128411;13078;13503.797443038558;13609.591712248648;4799.832005879917;9294.488803115622;9629.951850240705;11626.209709669449;11636.650900473;11083.33499989971;11101.11191104726;11218.943905280421;11360.772784544428;11635.47640356203;11291.322520648788;11488.080953523866;11740.354280514654;11811.350375729417;11876.643700688994;8117.878231826409;8175.345972322319;8414.621342039745;8554.18735220153;8564.443311184754;9631.614735410785;11060.17048721333;11501.367424791382;11567.07463402909;11678.88321116778 04:30:24 INFO - PROCESS | 1633 | |3;mootools.html;1448.6088756035876;1455.9708805823884;1462.692834504754;1482.4514929476095;1483.3621542736626;7792.92207077929;8164.755057348281;8178.182181781819;8191.017077950647;8203.384746144033;7221.56670599763;7456.739014134507;7805.687772489094;7965.60171991401;8039.517628942268;2275.260540324393;2308.35366097493;2309.930702078932;2311.7572654871246;2313.7570555091725;2300.1029598456616;2326.2439707095177;2328.359701082199;2332.300309907021;2338.953220935589;2195.7804219577993;2253.954920901581;2267.5804976079394;2302.654601809734;2308.203669733939;2101.548167144071;2262.2082271205113;2286.691296674961;2287.7826606472527;2289.3933107726466;2034.9287774927955;2301.5972204864247;2305.7348404933314;2308.376738280655;2309.445733024062;8067.636956336979;8117.878231826468;8136.1050284468665;8140.389470789714;8144.6742130314715;4862.878428039292;5016.924746128811;5028.924566131511;5030.698158110525;5033.421156566968;3240.206149493389;3259.0385836178384;3342.4819153031317;3377.172592714776;3424.2466657335344;1182.0484509969472;1190;1204.3496511883573;1229.416027386993;1234.0929416878587;10178.083972442442;10257.589696412135;10266.486675666261;10273.28087033908;10291.228157888188;4216.894577635522;4407.942093897442;4481.551844815557;4497.797599108048;4505;9026.368154229272;9028.277737780962;9035.728928132166;9064.184223419923;9092.954535227282;5073.797048118107;5075.441701412841;5075.492450754969;5076.263941728429;5078.289039534468;4161.8959526012095;4260.616544510947;4567.086582683477;4576.542345765397;4595.494495605481;3672.467492213614;3732.9626703733156;3783.981080094582;3823.935075059492;3825.9808700956874;1248.463160840829;1254.2565833491453;1259.9370031498388;1267.9302638354795;1270.5807083662553;885.5793498088485;906.981860362789;907.1427501011481;942.2744486745167;946.986634868579;5482.424345443751;5525.889482210414;5596.440355964371;5602.075657516546;5790.160426738099;877.5612193903048;1004.1113614451264;1009.1119814563284;1015.5480811039164;1050.8738951325736;3691.7969511677115;3692.4276737105793;3739.700823934079;3750.568684601306;3812.637799409107;9359.391639543408;9683.515824208902;9692.660756873476;9706.369086009387;9714.319997600242;9618.951905240428;9646.228301735844;9657.372270802376;9658.082482164324;9664.420134791935;7176.102987126609;7481.999999999891;7496.062992125984;7514.924850751422;7642.961785191039;5398.028354896156;5485.588580856372;5532.695701736282;5536.806211782568;5575.498205161555;8610.698625548075;8732.388732788644;8900.910990890008;8951.597178126869;9041.367104302635;8796.956015219883;8828.205461508494;9044.095590441166;9122.270218382646;9156.221721153777;6494.1232933553365;6686.632235227109;6701.865962680817;6976.895346569907;7004;7480.588567628833;7729.912923510733;7746.8450630985935;7830.451868369388;7834.373250140089;6926.376626103674;7017.684204210721;7039.436845052486;7088.645567721696;7177.174624917992;6227.252729672468;6918.308169183243;6924.134483189601;6957.686904089429;6986.930130698831;6143.815685529441;6234.812955611339;6330.1454303668415;6330.746770129143;6342.904856427065;4536.795844186953;4813.9037219256115;4854.660173787801;4928.186849169991;4980.228064649985;4846.36997190363;4905.950940490549;4928.112939670894;5017.096922553976;5022.970291090391 04:30:24 INFO - PROCESS | 1633 | |4;prototype.html;301.9260281231098;356.78771131176956;367.25447341895944;373.20879734961886;376.689231384108;4697.741624210667;4741.478437371887;5219.217117432387;5256.476891092278;5294.788208471657;4250.362445633156;4582.266837306031;4648.605967926512;4991.675541089827;5033.345665063546;3412.6928576428118;3550.964490355102;3669.6513831185994;3976.065624578236;4030.798460076999;3226.370857682753;3617.1861331200535;3705.6844820088886;4000.359942409215;4080.9795951020205;3227.8379783278;3836.558795738498;4031.9596804031876;4062.5124985001694;4071.9185616287655;3679.8528058877614;4072.9389059164137;4091.6112969267874;4094.344904815215;4102.948525737132;2904.5332107285685;3035.6812534683763;3149.228439032429;3468.254325320043;3586.8206589670413;4239.342901850218;5047.038075111021;5070.492950704936;5074.670146440507;5099.69401835891;4432.810993611039;4513.956446905641;4608.101420223058;4662.114198302312;4720.63968015992;4691.507391723854;4798.1843086675;5193.532582067632;5527.806526771584;5533.501984821385;4012.859549915768;4015;4018.6584140348104;4018.8794336169963;4045.4134150548007;4790.221671430562;5466.808661696861;5763.09817760134;5800.303963524319;5857.970710146465;3713.848706900815;3840.2703486338064;3889.591592882763;3935.940960885589;4069.91860162801;4132.380142978517;4136.296829538986;4143.7928103594695;4151.858238984303;4159.209750147463;4436.467623885154;4670.346151538787;4683.812647494062;4727.699882532263;4738.218193998021;4552.7723613819835;4633.4671512775785;5157;5536.557075433956;5567.66594004361;1202.4048096192441;1323.9404226809816;1339.9732005360033;1358.585631382438;1364;794.9165337639464;890.6882591093066;899.1188635137602;916.7524768312518;969.8205831921117;4546.909061818745;4933.728644924492;5049.722265275445;5056.721880296545;5061.670991385622;5142.460041695568;5579.944200558024;5591.7483713233005;5604.355499117653;5632.408597097327;4100.138970816162;4472.8019466081905;4629.65277604181;4813.78337974785;5057.266696329085;2290.9496001079274;2497.8251522393257;2513.7234904160528;2787.8466684332557;2854.557543580748;4502.527234640381;5066.897237646409;5374.5700343972385;5512;5569.721513924369;4888.755562221946;4895.412550493964;5325.227841962971;5502.724863756796;5577.888442231133;4793.712377257376;4937.925931111036;5062.797488100435;5398.001369746609;5578.804741834017;4478.9552104478535;5297.788088476572;5385.872580330826;5432.18517222404;5504.972475137599;4231.576842315744;4715.6796097092865;4789.473157952692;5147.2682305328335;5267.394249661338;4753.477117517001;5005.173111814234;5518.613697041169;5578.972105139448;5583.497485226349;3456.4469684850305;3513.118004604875;3687.8156092194745;3828.578856325746;3876.2635099331037;5123.410807757155;5127;5256.39551451588;5332.9733351333;5367.329083864517;4308;4604.608608268335;4613.5847773700525;4765.547273009059;5088.542031217208;4608.446986361658;4649.5350464953235;4790.473047964651;4953.405591329064;5191.949180073774;4857.081030039524;4985.302057711996;5017.896062866163;5052.8484145475695;5128.743562821918;4729.763511824463;5044.621653375938;5200.011997720421;5311.468853114658;5365.1222072274395;4408.625266852354;4757.763850990029;4948.727819969792;5386.118426641728;5397.757100930389 04:30:24 INFO - PROCESS | 1633 | |5;yui.html;707.2715103443448;715.9976033553029;781.4803155901326;811.3468657730529;828.6602492977878;16672.666546669076;17224.655506889856;17497.30010799568;17501.037442940633;17598.736018959728;15643.06141631503;16150.676986460263;16329.510114696548;16716.2477688504;16769.91615041926;857.9356548258875;871.9520426376563;879.6129702930722;892.7991201979568;895.6596493332524;694.1080711285998;884.7743825324533;884.8053428245776;885.6501681835704;885.6811547842772;745.2324106170653;903.9683611073614;933.2580598424236;935.2424536125726;938.8497840345547;924.1128516624049;924.9306302027375;924.9676261330821;925.047201382577;925.144241576539;520.8333333333306;686.7836631461074;689.0422312984955;690.0718533572337;690.8549204667027;11539.711507212303;12888.613341599766;14970.401183952737;15536.06783592988;15564.922175389163;13956;14000.719985600334;14041.368138433692;14071;14073.437062517487;11848.407579620984;12130.575429859913;12276.570320038845;12290.016798656176;12537.373131343396;3631.7094632429344;3664.6885014773775;3820.4842346283176;3882.9805850970847;3889;90425.54787226088;98334.03331933393;103175.48412257891;103508;104461.4776926118;10402;10680.679579612624;10910.890891091147;10968.780624387467;11245.83131253032;21459.78540214609;22098.22656207025;22277.66583501249;22283.654687335813;22330.106795728312;7676.232376762279;7703.922960770321;7731.226877312336;7779.222077792176;7950.165232650604;4253.319468885025;4361.5856493633055;4366.3013917773005;4423.734575925455;4424.269995450714;3566.6968307694137;3670.871519496858;3673.81630918453;3683.2265224303196;3689.7048236141045;635.3709827270968;637.003090163892;641.3330136657823;643.9291677915426;647.779754883342;692.4322055914203;703.1351437731619;704.8222331266454;712.9108861392326;719.9208087110413;9006.423875821705;9659.855102173473;9721.173700235418;9734.853977190347;10659.84010239847;593.6022864680674;676.2485064526173;695.2352412346379;702.537768847217;712.3161764705941;4866.683665561727;5012.8496145115705;5024.924626130611;5027.723475208899;5033.993201359743;90867;91820.54089729508;97729;98007;99888.00111998932;76079.61960190164;81242;89808.10191898128;90798.09201907896;92100.86890442047;74396.62801686065;74660.62669686617;74803;75561.86657200036;75663.62168189124;9566.378185418203;10619.044286014296;10894.945525272324;11104.666859994213;11214.794536971316;49293.50706492889;50355;50570.74714626403;51981.480185197666;52123.739381302854;68586.314136858;71211.93182102169;71724;72639.27360726536;72843.27156728577;11081.722956926142;12945.54690585825;13000.154989925624;13031.47874085026;13195.934020329836;13905;14908.18005009735;14966.251687415803;15101.47144849925;15601.063936163866;11540.763086966264;13542.526011589547;13774.793378099537;13919.582412527236;14363.56909292723;13509.59471215865;13743.656408589864;13903.652408689459;14317.284135793378;14361.712765744836;13436.731265374834;13603.319834008458;13617.319134043457;13632.318384080558;13656.795148072588;9421.434713917188;10661.466926653482;11433.371164585695;11536.25014374063;11874.109441791727;10812.297200681931;10937.890621094006;11037.724056898642;11061.612843550438;11071.612493562687 04:30:24 INFO - PROCESS | 1633 | __end_tp_report 04:30:24 INFO - PROCESS | 1633 | __start_cc_report 04:30:24 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,1805 04:30:24 INFO - PROCESS | 1633 | __end_cc_report 04:30:24 INFO - PROCESS | 1633 | __startTimestamp1484310624620__endTimestamp 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | ------- Summary: start ------- 04:30:24 INFO - PROCESS | 1633 | Number of tests: 6 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5604.18 Median:5814.19 stddev:643.44 (11.1%) stddev-sans-first:618.52 04:30:24 INFO - PROCESS | 1633 | Values: 3257.6 3258.1 3283.5 3295.0 3347.4 5429.2 5494.8 5495.2 5523.3 5534.8 5287.2 5498.3 5565.4 5616.0 5655.2 4111.8 5027.2 5051.1 5074.5 5107.1 4313.7 5079.7 5181.2 5199.6 5250.6 5175.0 5177.8 5196.6 5201.9 5204.8 4992.4 5042.1 5053.4 5057.2 5101.5 5059.5 5090.7 5095.9 5136.9 5137.8 5533.4 5576.0 5576.1 5592.7 5621.7 5889.2 5918.5 5944.5 6058.8 6106.7 6094.6 6115.2 6188.7 6200.7 6231.4 5596.9 5669.4 5677.6 5684.7 5706.6 6106.4 6269.3 6289.4 6293.1 6297.9 5862.6 5892.8 5897.4 5956.8 6030.0 5951.2 6122.7 6146.7 6150.1 6157.4 4907.8 5899.6 5979.1 6009.2 6066.1 6129.9 6133.9 6146.2 6146.8 6148.4 4780.4 4824.3 4831.3 4850.5 4869.6 4337.6 4358.6 4376.1 4399.8 4435.4 5621.6 5641.0 5646.7 5668.5 5676.7 5895.9 5925.3 5979.1 6230.3 6252.2 5533.9 5540.1 5579.7 5592.0 5603.0 5312.2 5370.3 5373.0 5379.4 5416.4 6192.0 6192.2 6195.3 6198.7 6204.6 5112.5 6158.2 6194.4 6197.8 6247.7 6114.6 6154.5 6271.5 6314.7 6324.8 6097.1 6097.5 6113.8 6116.6 6141.9 6099.2 6161.8 6184.1 6187.4 6198.4 5780.5 5848.5 5850.7 5938.6 5945.4 5763.7 5902.5 5925.1 5946.5 5954.2 5737.8 5807.9 5814.2 5830.8 5901.9 5063.7 6007.0 6012.0 6017.7 6022.2 5836.7 5839.9 5841.5 5842.7 5849.8 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:63990.05 Median:40450.80 stddev:134359.08 (332.2%) stddev-sans-first:134651.28 04:30:24 INFO - PROCESS | 1633 | Values: 444.3 505.4 519.3 528.6 536.3 30550.7 40141.8 41592.2 41690.8 42088.8 43142.0 43360.4 43443.0 43467.3 43509.8 9191.2 9400.7 9402.2 9414.3 9420.9 8406.3 8602.4 11131.0 11373.0 11405.1 11330.4 11370.7 11379.8 11404.7 11433.3 11314.6 11375.0 11387.4 11408.7 11410.3 8293.8 8536.2 11007.4 11236.6 11250.4 40037.4 40369.8 40378.4 40448.8 40452.8 4071.4 4079.7 4094.4 4110.6 4121.1 30375.9 30631.0 31284.8 31308.4 31429.0 3168.2 3268.4 3283.3 3322.5 3326.0 314761.0 317152.0 418352.0 423264.0 423645.0 50024.7 59665.4 59802.7 59853.1 60035.4 62964.0 62977.4 63031.0 63114.7 63176.7 15766.8 16004.1 16004.2 16005.8 16046.4 11276.6 12284.4 12378.5 12392.4 12406.8 14635.4 15092.0 15130.0 15146.2 15152.5 5763.9 6720.7 6761.5 6781.3 6813.1 881.1 888.3 902.1 908.8 925.6 28734.6 30014.0 30058.7 30251.7 30872.1 934.6 1006.0 1048.4 1053.4 1076.4 13781.4 14377.8 14408.3 14623.3 14730.6 592722.0 597914.0 598013.0 598827.0 599091.0 42852.0 51422.7 51434.0 51444.2 51563.5 1248.1 1431.2 1451.9 1455.7 1457.7 20993.9 25075.0 25996.0 27069.2 27069.7 44056.1 45713.5 45811.3 45824.1 46018.5 506516.0 508525.0 509512.0 509537.0 509566.0 23937.0 24241.4 24318.0 44127.8 45464.8 48429.0 49832.8 50039.0 50400.5 50606.5 38755.8 40292.8 41047.8 41128.8 42283.0 40561.0 40876.6 41014.0 41047.8 42071.0 32872.1 39675.4 40365.8 40486.8 42803.9 31675.8 36019.0 36122.3 36478.8 36573.5 25926.9 26204.0 26452.9 26767.0 26870.9 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:35665.40 Median:21550.46 stddev:100628.27 (466.9%) stddev-sans-first:100875.66 04:30:24 INFO - PROCESS | 1633 | Values: 370.1 390.4 395.6 400.2 423.0 14637.0 15974.9 19050.5 21295.9 21321.4 15809.3 21560.0 21950.5 22011.3 22320.2 5333.4 5494.8 5698.9 5870.5 5968.4 5981.9 6020.5 6024.6 6036.3 6036.8 3850.8 3888.5 4054.6 4078.0 6472.3 6505.7 6521.9 6525.3 6531.6 6556.4 5611.1 5751.6 5795.1 5806.3 5808.6 13696.3 13867.9 13894.6 20724.8 21028.6 2540.7 2563.4 2585.4 2586.4 2616.6 8643.7 10170.8 10250.4 10330.1 10546.9 1767.3 1803.3 1832.1 1843.8 1845.5 119836.4 128293.4 129795.0 131570.3 132193.0 11426.6 11787.9 11813.2 11836.5 11839.4 15599.1 15624.8 15630.4 15633.9 15647.8 8467.6 8630.9 8647.6 8780.9 8800.6 7643.4 7772.5 8362.5 8407.9 8556.3 4503.9 4697.5 4790.5 4791.3 4896.0 1829.2 2028.8 2037.9 2041.6 2042.4 968.4 989.6 993.7 997.8 1038.3 20946.5 21238.9 21532.8 21540.9 21621.2 1028.7 1032.3 1033.9 1075.1 1079.9 10921.9 11153.6 11203.3 11203.4 11219.7 504974.0 576453.0 582641.0 591295.0 715216.0 118348.4 121215.7 152126.0 152463.7 152547.2 1029.1 1069.2 1088.5 1089.6 1090.7 19531.8 19704.8 19846.7 20025.8 20050.9 47917.8 50441.7 58204.0 58571.7 59033.7 87306.0 87801.0 88512.6 88674.0 90924.0 8261.6 8318.3 8343.8 8398.2 8625.5 12180.8 13048.2 13078.0 13503.8 13609.6 4799.8 9294.5 9630.0 11626.2 11636.7 11083.3 11101.1 11218.9 11360.8 11635.5 11291.3 11488.1 11740.4 11811.4 11876.6 8117.9 8175.3 8414.6 8554.2 8564.4 9631.6 11060.2 11501.4 11567.1 11678.9 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:5286.13 Median:5075.47 stddev:2824.17 (55.6%) stddev-sans-first:2817.37 04:30:24 INFO - PROCESS | 1633 | Values: 1448.6 1456.0 1462.7 1482.5 1483.4 7792.9 8164.8 8178.2 8191.0 8203.4 7221.6 7456.7 7805.7 7965.6 8039.5 2275.3 2308.4 2309.9 2311.8 2313.8 2300.1 2326.2 2328.4 2332.3 2339.0 2195.8 2254.0 2267.6 2302.7 2308.2 2101.5 2262.2 2286.7 2287.8 2289.4 2034.9 2301.6 2305.7 2308.4 2309.4 8067.6 8117.9 8136.1 8140.4 8144.7 4862.9 5016.9 5028.9 5030.7 5033.4 3240.2 3259.0 3342.5 3377.2 3424.2 1182.0 1190.0 1204.3 1229.4 1234.1 10178.1 10257.6 10266.5 10273.3 10291.2 4216.9 4407.9 4481.6 4497.8 4505.0 9026.4 9028.3 9035.7 9064.2 9093.0 5073.8 5075.4 5075.5 5076.3 5078.3 4161.9 4260.6 4567.1 4576.5 4595.5 3672.5 3733.0 3784.0 3823.9 3826.0 1248.5 1254.3 1259.9 1267.9 1270.6 885.6 907.0 907.1 942.3 947.0 5482.4 5525.9 5596.4 5602.1 5790.2 877.6 1004.1 1009.1 1015.5 1050.9 3691.8 3692.4 3739.7 3750.6 3812.6 9359.4 9683.5 9692.7 9706.4 9714.3 9619.0 9646.2 9657.4 9658.1 9664.4 7176.1 7482.0 7496.1 7514.9 7643.0 5398.0 5485.6 5532.7 5536.8 5575.5 8610.7 8732.4 8900.9 8951.6 9041.4 8797.0 8828.2 9044.1 9122.3 9156.2 6494.1 6686.6 6701.9 6976.9 7004.0 7480.6 7729.9 7746.8 7830.5 7834.4 6926.4 7017.7 7039.4 7088.6 7177.2 6227.3 6918.3 6924.1 6957.7 6986.9 6143.8 6234.8 6330.1 6330.7 6342.9 4536.8 4813.9 4854.7 4928.2 4980.2 4846.4 4906.0 4928.1 5017.1 5023.0 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4304.17 Median:4728.73 stddev:1270.34 (26.9%) stddev-sans-first:1237.87 04:30:24 INFO - PROCESS | 1633 | Values: 301.9 356.8 367.3 373.2 376.7 4697.7 4741.5 5219.2 5256.5 5294.8 4250.4 4582.3 4648.6 4991.7 5033.3 3412.7 3551.0 3669.7 3976.1 4030.8 3226.4 3617.2 3705.7 4000.4 4081.0 3227.8 3836.6 4032.0 4062.5 4071.9 3679.9 4072.9 4091.6 4094.3 4102.9 2904.5 3035.7 3149.2 3468.3 3586.8 4239.3 5047.0 5070.5 5074.7 5099.7 4432.8 4514.0 4608.1 4662.1 4720.6 4691.5 4798.2 5193.5 5527.8 5533.5 4012.9 4015.0 4018.7 4018.9 4045.4 4790.2 5466.8 5763.1 5800.3 5858.0 3713.8 3840.3 3889.6 3935.9 4069.9 4132.4 4136.3 4143.8 4151.9 4159.2 4436.5 4670.3 4683.8 4727.7 4738.2 4552.8 4633.5 5157.0 5536.6 5567.7 1202.4 1323.9 1340.0 1358.6 1364.0 794.9 890.7 899.1 916.8 969.8 4546.9 4933.7 5049.7 5056.7 5061.7 5142.5 5579.9 5591.7 5604.4 5632.4 4100.1 4472.8 4629.7 4813.8 5057.3 2290.9 2497.8 2513.7 2787.8 2854.6 4502.5 5066.9 5374.6 5512.0 5569.7 4888.8 4895.4 5325.2 5502.7 5577.9 4793.7 4937.9 5062.8 5398.0 5578.8 4479.0 5297.8 5385.9 5432.2 5505.0 4231.6 4715.7 4789.5 5147.3 5267.4 4753.5 5005.2 5518.6 5579.0 5583.5 3456.4 3513.1 3687.8 3828.6 3876.3 5123.4 5127.0 5256.4 5333.0 5367.3 4308.0 4604.6 4613.6 4765.5 5088.5 4608.4 4649.5 4790.5 4953.4 5191.9 4857.1 4985.3 5017.9 5052.8 5128.7 4729.8 5044.6 5200.0 5311.5 5365.1 4408.6 4757.8 4948.7 5386.1 5397.8 04:30:24 INFO - PROCESS | 1633 | 04:30:24 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20231.11 Median:4424.00 stddev:28194.57 (637.3%) stddev-sans-first:28235.55 04:30:24 INFO - PROCESS | 1633 | Values: 707.3 716.0 781.5 811.3 828.7 16672.7 17224.7 17497.3 17501.0 17598.7 15643.1 16150.7 16329.5 16716.2 16769.9 857.9 872.0 879.6 892.8 895.7 694.1 884.8 884.8 885.7 885.7 745.2 904.0 933.3 935.2 938.8 924.1 924.9 925.0 925.0 925.1 520.8 686.8 689.0 690.1 690.9 11539.7 12888.6 14970.4 15536.1 15564.9 13956.0 14000.7 14041.4 14071.0 14073.4 11848.4 12130.6 12276.6 12290.0 12537.4 3631.7 3664.7 3820.5 3883.0 3889.0 90425.5 98334.0 103175.5 103508.0 104461.5 10402.0 10680.7 10910.9 10968.8 11245.8 21459.8 22098.2 22277.7 22283.7 22330.1 7676.2 7703.9 7731.2 7779.2 7950.2 4253.3 4361.6 4366.3 4423.7 4424.3 3566.7 3670.9 3673.8 3683.2 3689.7 635.4 637.0 641.3 643.9 647.8 692.4 703.1 704.8 712.9 719.9 9006.4 9659.9 9721.2 9734.9 10659.8 593.6 676.2 695.2 702.5 712.3 4866.7 5012.8 5024.9 5027.7 5034.0 90867.0 91820.5 97729.0 98007.0 99888.0 76079.6 81242.0 89808.1 90798.1 92100.9 74396.6 74660.6 74803.0 75561.9 75663.6 9566.4 10619.0 10894.9 11104.7 11214.8 49293.5 50355.0 50570.7 51981.5 52123.7 68586.3 71211.9 71724.0 72639.3 72843.3 11081.7 12945.5 13000.2 13031.5 13195.9 13905.0 14908.2 14966.3 15101.5 15601.1 11540.8 13542.5 13774.8 13919.6 14363.6 13509.6 13743.7 13903.7 14317.3 14361.7 13436.7 13603.3 13617.3 13632.3 13656.8 9421.4 10661.5 11433.4 11536.3 11874.1 10812.3 10937.9 11037.7 11061.6 11071.6 04:30:24 INFO - PROCESS | 1633 | -------- Summary: end -------- 04:30:24 INFO - PROCESS | 1633 | 04:30:25 INFO - TEST-INFO | 1633: exit 0 04:30:25 INFO - TEST-OK | dromaeo_css | took 1118462ms 04:30:25 INFO - TEST-START | kraken 04:30:25 INFO - operating with platform_type : mac_ 04:30:25 INFO - Initialising browser for kraken test... 04:30:25 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpt6NHNy/profile http://localhost:49243/getInfo.html) 04:30:27 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 04:30:27 INFO - PROCESS | 1643 | colorDepth:24 04:30:27 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 04:30:27 INFO - PROCESS | 1643 | __metrics 04:30:27 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:30:27 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:30:28 INFO - TEST-INFO | 1643: exit 0 04:30:28 INFO - Browser initialized. 04:30:28 INFO - Running cycle 1/1 for kraken test... 04:30:28 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpt6NHNy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:40 INFO - PROCESS | 1644 | RSS: Main: 235601920 04:30:40 INFO - PROCESS | 1644 | 04:30:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 04:30:44 INFO - PROCESS | 1644 | RSS: Main: 287809536 04:30:44 INFO - PROCESS | 1644 | 04:30:47 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 04:30:47 INFO - PROCESS | 1644 | RSS: Main: 286908416 04:30:47 INFO - PROCESS | 1644 | 04:30:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 04:30:51 INFO - PROCESS | 1644 | RSS: Main: 378748928 04:30:51 INFO - PROCESS | 1644 | 04:30:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 04:30:54 INFO - PROCESS | 1644 | RSS: Main: 283025408 04:30:54 INFO - PROCESS | 1644 | 04:30:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:30:56 INFO - PROCESS | 1644 | RSS: Main: 286765056 04:30:56 INFO - PROCESS | 1644 | 04:30:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 04:31:00 INFO - PROCESS | 1644 | RSS: Main: 328929280 04:31:00 INFO - PROCESS | 1644 | 04:31:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 04:31:03 INFO - PROCESS | 1644 | RSS: Main: 330248192 04:31:03 INFO - PROCESS | 1644 | 04:31:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 04:31:07 INFO - PROCESS | 1644 | RSS: Main: 327766016 04:31:07 INFO - PROCESS | 1644 | 04:31:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:31:09 INFO - PROCESS | 1644 | RSS: Main: 303902720 04:31:09 INFO - PROCESS | 1644 | 04:31:11 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:31:12 INFO - PROCESS | 1644 | RSS: Main: 307478528 04:31:12 INFO - PROCESS | 1644 | 04:31:14 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:31:14 INFO - PROCESS | 1644 | RSS: Main: 308842496 04:31:14 INFO - PROCESS | 1644 | 04:31:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:31:18 INFO - PROCESS | 1644 | RSS: Main: 325287936 04:31:18 INFO - PROCESS | 1644 | 04:31:21 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:31:22 INFO - PROCESS | 1644 | RSS: Main: 319647744 04:31:22 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 04:31:24 INFO - PROCESS | 1644 | RSS: Main: 364089344 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | __start_tp_report 04:31:24 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 04:31:24 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 04:31:24 INFO - PROCESS | 1644 | |i|pagename|runs| 04:31:24 INFO - PROCESS | 1644 | |0;ai-astar;114;114;115;114;113;161;164;114;112;114 04:31:24 INFO - PROCESS | 1644 | |1;audio-beat-detection;145;109;113;174;112;111;109;110;129;111 04:31:24 INFO - PROCESS | 1644 | |2;audio-dft;183;180;185;186;183;180;182;184;182;184 04:31:24 INFO - PROCESS | 1644 | |3;audio-fft;80;59;58;87;60;59;60;58;73;59 04:31:24 INFO - PROCESS | 1644 | |4;audio-oscillator;91;70;85;70;94;99;116;68;68;83 04:31:24 INFO - PROCESS | 1644 | |5;imaging-gaussian-blur;88;84;86;83;86;83;86;84;86;83 04:31:24 INFO - PROCESS | 1644 | |6;imaging-darkroom;92;92;93;93;92;92;93;93;108;149 04:31:24 INFO - PROCESS | 1644 | |7;imaging-desaturate;122;130;91;85;84;87;87;86;110;128 04:31:24 INFO - PROCESS | 1644 | |8;json-parse-financial;56;55;53;54;55;55;55;55;55;55 04:31:24 INFO - PROCESS | 1644 | |9;json-stringify-tinderbox;66;79;75;72;71;69;69;69;68;69 04:31:24 INFO - PROCESS | 1644 | |10;stanford-crypto-aes;87;86;90;86;85;86;88;88;87;87 04:31:24 INFO - PROCESS | 1644 | |11;stanford-crypto-ccm;139;156;138;145;144;146;131;140;180;153 04:31:24 INFO - PROCESS | 1644 | |12;stanford-crypto-pbkdf2;199;229;183;170;170;227;172;173;177;173 04:31:24 INFO - PROCESS | 1644 | |13;stanford-crypto-sha256-iterative;68;68;68;68;67;63;61;68;66;67 04:31:24 INFO - PROCESS | 1644 | __end_tp_report 04:31:24 INFO - PROCESS | 1644 | __start_cc_report 04:31:24 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1952 04:31:24 INFO - PROCESS | 1644 | __end_cc_report 04:31:24 INFO - PROCESS | 1644 | __startTimestamp1484310684318__endTimestamp 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | ------- Summary: start ------- 04:31:24 INFO - PROCESS | 1644 | Number of tests: 14 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#0] ai-astar Cycles:10 Average:123.50 Median:114.00 stddev:20.58 (18.1%) stddev-sans-first:21.54 04:31:24 INFO - PROCESS | 1644 | Values: 114.0 114.0 115.0 114.0 113.0 161.0 164.0 114.0 112.0 114.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:112.50 stddev:21.57 (19.2%) stddev-sans-first:21.25 04:31:24 INFO - PROCESS | 1644 | Values: 145.0 109.0 113.0 174.0 112.0 111.0 109.0 110.0 129.0 111.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.97 (1.1%) stddev-sans-first:2.09 04:31:24 INFO - PROCESS | 1644 | Values: 183.0 180.0 185.0 186.0 183.0 180.0 182.0 184.0 182.0 184.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#3] audio-fft Cycles:10 Average:65.30 Median:60.00 stddev:10.69 (17.8%) stddev-sans-first:9.92 04:31:24 INFO - PROCESS | 1644 | Values: 80.0 59.0 58.0 87.0 60.0 59.0 60.0 58.0 73.0 59.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:84.00 stddev:15.99 (19.0%) stddev-sans-first:16.79 04:31:24 INFO - PROCESS | 1644 | Values: 91.0 70.0 85.0 70.0 94.0 99.0 116.0 68.0 68.0 83.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.73 (2.0%) stddev-sans-first:1.42 04:31:24 INFO - PROCESS | 1644 | Values: 88.0 84.0 86.0 83.0 86.0 83.0 86.0 84.0 86.0 83.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:92.50 stddev:18.00 (19.5%) stddev-sans-first:18.88 04:31:24 INFO - PROCESS | 1644 | Values: 92.0 92.0 93.0 93.0 92.0 92.0 93.0 93.0 108.0 149.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:85.50 stddev:19.30 (22.6%) stddev-sans-first:18.92 04:31:24 INFO - PROCESS | 1644 | Values: 122.0 130.0 91.0 85.0 84.0 87.0 87.0 86.0 110.0 128.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.79 (1.4%) stddev-sans-first:0.71 04:31:24 INFO - PROCESS | 1644 | Values: 56.0 55.0 53.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.00 stddev:3.80 (5.4%) stddev-sans-first:3.63 04:31:24 INFO - PROCESS | 1644 | Values: 66.0 79.0 75.0 72.0 71.0 69.0 69.0 69.0 68.0 69.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.50 04:31:24 INFO - PROCESS | 1644 | Values: 87.0 86.0 90.0 86.0 85.0 86.0 88.0 88.0 87.0 87.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:145.50 stddev:13.62 (9.4%) stddev-sans-first:14.12 04:31:24 INFO - PROCESS | 1644 | Values: 139.0 156.0 138.0 145.0 144.0 146.0 131.0 140.0 180.0 153.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:180.00 stddev:23.14 (12.9%) stddev-sans-first:24.15 04:31:24 INFO - PROCESS | 1644 | Values: 199.0 229.0 183.0 170.0 170.0 227.0 172.0 173.0 177.0 173.0 04:31:24 INFO - PROCESS | 1644 | 04:31:24 INFO - PROCESS | 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:68.00 stddev:2.46 (3.6%) stddev-sans-first:2.54 04:31:24 INFO - PROCESS | 1644 | Values: 68.0 68.0 68.0 68.0 67.0 63.0 61.0 68.0 66.0 67.0 04:31:24 INFO - PROCESS | 1644 | -------- Summary: end -------- 04:31:24 INFO - PROCESS | 1644 | 04:31:25 INFO - TEST-INFO | 1644: exit 0 04:31:25 INFO - TEST-OK | kraken | took 59895ms 04:31:25 INFO - SUITE-END | took 1178s 04:31:25 INFO - Completed test suite (00:19:38) 04:31:25 INFO - javascript benchmark 04:31:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.6416594174643, 3258.1203075169706, 3283.507473878918, 3295.0279667498085, 3347.4309367407536, 5429.239906413095, 5494.780208791649, 5495.20319553665, 5523.25436066131, 5534.833954981357, 5287.180487024508, 5498.257735205746, 5565.359983601887, 5616.045272303708, 5655.236543066674, 4111.794410279489, 5027.170516864713, 5051.1413059779925, 5074.467180946003, 5107.131787596118, 4313.719608225455, 5079.746012699368, 5181.222816577525, 5199.636025478199, 5250.553702935236, 5175.01674681813, 5177.767000484987, 5196.58427325815, 5201.895962080737, 5204.817831375903, 4992.375953005874, 5042.1428357179, 5053.36832895888, 5057.165567681328, 5101.51535604117, 5059.494050594948, 5090.669106508065, 5095.949040509585, 5136.948630513722, 5137.845864624067, 5533.418991005967, 5576.024195765765, 5576.052071147915, 5592.692401917893, 5621.7189140542805, 5889.204957330749, 5918.526517878559, 5944.524438044946, 6058.848528786772, 6106.664133472657, 6094.573379863411, 6115.218884034779, 6188.721507532172, 6200.689965501707, 6231.376862313778, 5596.944030559724, 5669.4047125051275, 5677.630953987978, 5684.687342196219, 5706.572007099443, 6106.430331452215, 6269.341719119427, 6289.371062893765, 6293.118963345135, 6297.874042519124, 5862.560307976833, 5892.793752218652, 5897.380775018647, 5956.821295361146, 6029.969850150721, 5951.166836642873, 6122.66325352101, 6146.7233974471255, 6150.108234306001, 6157.415045570664, 4907.803687852446, 5899.557533185029, 5979.073243647242, 6009.218801555857, 6066.059760737093, 6129.8898215898025, 6133.916185115316, 6146.170267013986, 6146.754129834846, 6148.415900489446, 4780.378550788375, 4824.324594556765, 4831.347768051268, 4850.466448690641, 4869.561739443397, 4337.609615134653, 4358.585934336233, 4376.081022985209, 4399.780010999437, 4435.423394958635, 5621.606487545832, 5641.0, 5646.6894320811925, 5668.461496157858, 5676.716164191774, 5895.882082358415, 5925.281668316226, 5979.103134529768, 6230.283517395467, 6252.187215661935, 5533.944660553423, 5540.141278101901, 5579.7489112990015, 5591.9720401397735, 5603.019471592455, 5312.2297266896085, 5370.3287089113865, 5372.973135134299, 5379.381371142291, 5416.350037995466, 6191.9690401547705, 6192.195014648157, 6195.287541932736, 6198.721057552511, 6204.5967012144065, 5112.539871411442, 6158.199434073542, 6194.442500175006, 6197.752089916353, 6247.656378899204, 6114.631938157225, 6154.476869466145, 6271.498280137669, 6314.747410103544, 6324.841878953063, 6097.085437184458, 6097.4817140543555, 6113.816585502442, 6116.5718399711595, 6141.9385806143155, 6099.207103076572, 6161.815145545641, 6184.103305020837, 6187.443130118311, 6198.442140207403, 5780.479756821906, 5848.53211743051, 5850.707464626837, 5938.584299099021, 5945.375735547878, 5763.654180749001, 5902.498287645427, 5925.081612350093, 5946.524278057832, 5954.19618351517, 5737.770489180386, 5807.883842323215, 5814.186013958134, 5830.766769329349, 5901.852453688693, 5063.696178229318, 6007.038873780174, 6011.969940150271, 6017.699115044318, 6022.2171117754415, 5836.678982655825, 5839.854003649943, 5841.474267315962, 5842.737076831638, 5849.795257165979], "value": 5563.492077770534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.26030658952845, 505.4490605240287, 519.2989464223298, 528.6246764796994, 536.2787051415844, 30550.694493055064, 40141.79929100354, 41592.16815663685, 41690.79154604227, 42088.78955605225, 43142.0, 43360.447153048866, 43443.0, 43467.34798978018, 43509.782451087776, 9191.172794448516, 9400.670976515823, 9402.153806157445, 9414.293927955396, 9420.905790942106, 8406.327493800512, 8602.397832151752, 11130.998210161084, 11373.0, 11405.14461415393, 11330.376829274386, 11370.71573210668, 11379.829302560427, 11404.657860264206, 11433.256838305526, 11314.603988860392, 11375.0, 11387.373694446802, 11408.657740267763, 11410.315381077162, 8293.751187464386, 8536.231739143444, 11007.449627518592, 11236.606718764804, 11250.437478126143, 40037.39943900844, 40369.79815100935, 40378.394324085166, 40448.79775601133, 40452.797736011424, 4071.389291606253, 4079.740380157637, 4094.447249621293, 4110.588941105895, 4121.093359460914, 30375.887581309984, 30631.0, 31284.843575781975, 31308.373832523223, 31429.0, 3168.1604374840686, 3268.4116858965376, 3283.326917981808, 3322.4684050552037, 3325.96674033259, 314761.0, 317152.0, 418351.999999997, 423264.0, 423645.0, 50024.74987625112, 59665.403345965984, 59802.70098649479, 59853.10220346698, 60035.399646003854, 62964.0, 62977.370226298066, 63031.0, 63114.68442657757, 63176.68411657912, 15766.76349854753, 16004.119773412574, 16004.199790010452, 16005.839941600434, 16046.358145674043, 11276.605318813803, 12284.447199876027, 12378.504859805507, 12392.380380980914, 12406.81389779154, 14635.414583416758, 15092.0, 15130.0, 15146.242687865562, 15152.48475152498, 5763.8559036024435, 6720.731170753213, 6761.526693131532, 6781.287964763727, 6813.092334146447, 881.1056777370974, 888.3426264564176, 902.1429641840147, 908.8409528332647, 925.5696101312922, 28734.568981465294, 30014.0, 30058.699413006027, 30251.705951458847, 30872.0738377849, 934.6354921580589, 1005.9547320370601, 1048.4128887822844, 1053.4153544782719, 1076.3864597179534, 13781.379837907318, 14377.78433323501, 14408.279586020657, 14623.341949612293, 14730.55808325752, 592722.0, 597914.0, 598013.0, 598827.0, 599091.0, 42852.0, 51422.74288628533, 51434.0, 51444.22833657498, 51563.48436515662, 1248.063952035973, 1431.1842249917656, 1451.8765904897991, 1455.716135353596, 1457.679310551677, 20993.89503052536, 25075.0, 25996.0, 27069.1879243623, 27069.72930270751, 44056.11887762163, 45713.542864570925, 45811.31283030824, 45824.08351833012, 46018.53981460143, 506516.0, 508525.0, 509512.0, 509537.0, 509566.0, 23937.0, 24241.39396515101, 24318.027278909354, 44127.77936110299, 45464.77267613641, 48429.0, 49832.750836245585, 50039.0, 50400.49599503958, 50606.49393506165, 38755.80622096871, 40292.79853600713, 41047.794761026, 41128.794356028026, 42283.0, 40561.0, 40876.59123408728, 41014.0, 41047.794761026, 42071.0, 32872.096859658785, 39675.404868927566, 40365.79817100896, 40486.797566011985, 42803.92990175271, 31675.841620791747, 36019.0, 36122.277554448236, 36478.81760591286, 36573.45139822958, 25926.87036564805, 26204.0, 26452.8677356612, 26767.0, 26870.865645671645], "value": 19781.457687911217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.1246551904746, 390.4163275902524, 395.59056376650165, 400.1696479804406, 422.9555896630854, 14637.024001759759, 15974.92012539937, 19050.523736906584, 21295.935203239853, 21321.36035918925, 15809.269895387846, 21560.02979865906, 21950.45123871904, 22011.258086125395, 22320.21879234227, 5333.4399888011785, 5494.752736126884, 5698.943010569903, 5870.471657550819, 5968.373320801296, 5981.88036239275, 6020.518358531307, 6024.578279520435, 6036.275646922375, 6036.849078773043, 3850.8267127979307, 3888.5333759948844, 4054.6350828425434, 4078.021274894019, 6472.255690595571, 6505.739770409178, 6521.902171467432, 6525.314841941623, 6531.575447595891, 6556.409923106896, 5611.102223644238, 5751.597388182829, 5795.1017592273265, 5806.303243610751, 5808.6224395414165, 13696.315184240848, 13867.930660346734, 13894.583162505141, 20724.756514609173, 21028.579428411347, 2540.733223011576, 2563.4104156044214, 2585.3924327782956, 2586.3663402466327, 2616.6467526883816, 8643.65425382984, 10170.796584068277, 10250.38497690141, 10330.12193963514, 10546.894531054668, 1767.3372485318005, 1803.2606631281112, 1832.065646520271, 1843.793570539007, 1845.501714537081, 119836.40081799623, 128293.41093203284, 129795.0, 131570.34214828865, 132193.0, 11426.600068997544, 11787.882121178678, 11813.173077884465, 11836.452937758635, 11839.408029598486, 15599.142047187286, 15624.76562851558, 15630.3747850087, 15633.921830390775, 15647.765283520528, 8467.618957146944, 8630.921134858143, 8647.610857511427, 8780.912190878138, 8800.60397282111, 7643.394887073666, 7772.455928085093, 8362.498250105013, 8407.873881891777, 8556.27271681914, 4503.887402814956, 4697.4832768395445, 4790.520947905182, 4791.329213910055, 4895.951040489621, 1829.2408650410198, 2028.7869773673847, 2037.94905127373, 2041.5610643711673, 2042.4375352855077, 968.4092703450889, 989.6486747204783, 993.7068564773409, 997.765525101615, 1038.2524582300732, 20946.476338091365, 21238.89380553087, 21532.78467215339, 21540.892295538422, 21621.24325648595, 1028.7428142964259, 1032.3135115148493, 1033.8862725100232, 1075.1237741240864, 1079.9082078023298, 10921.945390272998, 11153.609623663295, 11203.327800331843, 11203.383813890314, 11219.71950701239, 504974.0, 576453.0, 582641.0, 591295.0, 715216.0, 118348.40825795816, 121215.6662767095, 152126.0, 152463.71304430443, 152547.2372638152, 1029.1406675425953, 1069.2408390043156, 1088.455772113943, 1089.6349722842936, 1090.7000574841823, 19531.804681953567, 19704.76347585276, 19846.702299465807, 20025.799742002393, 20050.899745501178, 47917.76041119772, 50441.74779126081, 58204.0, 58571.707141465726, 59033.70483147557, 87306.0, 87801.0, 88512.59130382603, 88674.0, 90924.0, 8261.586920653823, 8318.251357377865, 8343.791405214919, 8398.160183981552, 8625.482471051757, 12180.817287740514, 13048.151870128411, 13078.0, 13503.797443038558, 13609.591712248648, 4799.832005879917, 9294.488803115622, 9629.951850240705, 11626.209709669449, 11636.650900473, 11083.33499989971, 11101.11191104726, 11218.943905280421, 11360.772784544428, 11635.47640356203, 11291.322520648788, 11488.080953523866, 11740.354280514654, 11811.350375729417, 11876.643700688994, 8117.878231826409, 8175.345972322319, 8414.621342039745, 8554.18735220153, 8564.443311184754, 9631.614735410785, 11060.17048721333, 11501.367424791382, 11567.07463402909, 11678.88321116778], "value": 9659.401800679105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.6088756035876, 1455.9708805823884, 1462.692834504754, 1482.4514929476095, 1483.3621542736626, 7792.92207077929, 8164.755057348281, 8178.182181781819, 8191.017077950647, 8203.384746144033, 7221.56670599763, 7456.739014134507, 7805.687772489094, 7965.60171991401, 8039.517628942268, 2275.260540324393, 2308.35366097493, 2309.930702078932, 2311.7572654871246, 2313.7570555091725, 2300.1029598456616, 2326.2439707095177, 2328.359701082199, 2332.300309907021, 2338.953220935589, 2195.7804219577993, 2253.954920901581, 2267.5804976079394, 2302.654601809734, 2308.203669733939, 2101.548167144071, 2262.2082271205113, 2286.691296674961, 2287.7826606472527, 2289.3933107726466, 2034.9287774927955, 2301.5972204864247, 2305.7348404933314, 2308.376738280655, 2309.445733024062, 8067.636956336979, 8117.878231826468, 8136.1050284468665, 8140.389470789714, 8144.6742130314715, 4862.878428039292, 5016.924746128811, 5028.924566131511, 5030.698158110525, 5033.421156566968, 3240.206149493389, 3259.0385836178384, 3342.4819153031317, 3377.172592714776, 3424.2466657335344, 1182.0484509969472, 1190.0, 1204.3496511883573, 1229.416027386993, 1234.0929416878587, 10178.083972442442, 10257.589696412135, 10266.486675666261, 10273.28087033908, 10291.228157888188, 4216.894577635522, 4407.942093897442, 4481.551844815557, 4497.797599108048, 4505.0, 9026.368154229272, 9028.277737780962, 9035.728928132166, 9064.184223419923, 9092.954535227282, 5073.797048118107, 5075.441701412841, 5075.492450754969, 5076.263941728429, 5078.289039534468, 4161.8959526012095, 4260.616544510947, 4567.086582683477, 4576.542345765397, 4595.494495605481, 3672.467492213614, 3732.9626703733156, 3783.981080094582, 3823.935075059492, 3825.9808700956874, 1248.463160840829, 1254.2565833491453, 1259.9370031498388, 1267.9302638354795, 1270.5807083662553, 885.5793498088485, 906.981860362789, 907.1427501011481, 942.2744486745167, 946.986634868579, 5482.424345443751, 5525.889482210414, 5596.440355964371, 5602.075657516546, 5790.160426738099, 877.5612193903048, 1004.1113614451264, 1009.1119814563284, 1015.5480811039164, 1050.8738951325736, 3691.7969511677115, 3692.4276737105793, 3739.700823934079, 3750.568684601306, 3812.637799409107, 9359.391639543408, 9683.515824208902, 9692.660756873476, 9706.369086009387, 9714.319997600242, 9618.951905240428, 9646.228301735844, 9657.372270802376, 9658.082482164324, 9664.420134791935, 7176.102987126609, 7481.999999999891, 7496.062992125984, 7514.924850751422, 7642.961785191039, 5398.028354896156, 5485.588580856372, 5532.695701736282, 5536.806211782568, 5575.498205161555, 8610.698625548075, 8732.388732788644, 8900.910990890008, 8951.597178126869, 9041.367104302635, 8796.956015219883, 8828.205461508494, 9044.095590441166, 9122.270218382646, 9156.221721153777, 6494.1232933553365, 6686.632235227109, 6701.865962680817, 6976.895346569907, 7004.0, 7480.588567628833, 7729.912923510733, 7746.8450630985935, 7830.451868369388, 7834.373250140089, 6926.376626103674, 7017.684204210721, 7039.436845052486, 7088.645567721696, 7177.174624917992, 6227.252729672468, 6918.308169183243, 6924.134483189601, 6957.686904089429, 6986.930130698831, 6143.815685529441, 6234.812955611339, 6330.1454303668415, 6330.746770129143, 6342.904856427065, 4536.795844186953, 4813.9037219256115, 4854.660173787801, 4928.186849169991, 4980.228064649985, 4846.36997190363, 4905.950940490549, 4928.112939670894, 5017.096922553976, 5022.970291090391], "value": 4337.768096406738, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.9260281231098, 356.78771131176956, 367.25447341895944, 373.20879734961886, 376.689231384108, 4697.741624210667, 4741.478437371887, 5219.217117432387, 5256.476891092278, 5294.788208471657, 4250.362445633156, 4582.266837306031, 4648.605967926512, 4991.675541089827, 5033.345665063546, 3412.6928576428118, 3550.964490355102, 3669.6513831185994, 3976.065624578236, 4030.798460076999, 3226.370857682753, 3617.1861331200535, 3705.6844820088886, 4000.359942409215, 4080.9795951020205, 3227.8379783278, 3836.558795738498, 4031.9596804031876, 4062.5124985001694, 4071.9185616287655, 3679.8528058877614, 4072.9389059164137, 4091.6112969267874, 4094.344904815215, 4102.948525737132, 2904.5332107285685, 3035.6812534683763, 3149.228439032429, 3468.254325320043, 3586.8206589670413, 4239.342901850218, 5047.038075111021, 5070.492950704936, 5074.670146440507, 5099.69401835891, 4432.810993611039, 4513.956446905641, 4608.101420223058, 4662.114198302312, 4720.63968015992, 4691.507391723854, 4798.1843086675, 5193.532582067632, 5527.806526771584, 5533.501984821385, 4012.859549915768, 4015.0, 4018.6584140348104, 4018.8794336169963, 4045.4134150548007, 4790.221671430562, 5466.808661696861, 5763.09817760134, 5800.303963524319, 5857.970710146465, 3713.848706900815, 3840.2703486338064, 3889.591592882763, 3935.940960885589, 4069.91860162801, 4132.380142978517, 4136.296829538986, 4143.7928103594695, 4151.858238984303, 4159.209750147463, 4436.467623885154, 4670.346151538787, 4683.812647494062, 4727.699882532263, 4738.218193998021, 4552.7723613819835, 4633.4671512775785, 5157.0, 5536.557075433956, 5567.66594004361, 1202.4048096192441, 1323.9404226809816, 1339.9732005360033, 1358.585631382438, 1364.0, 794.9165337639464, 890.6882591093066, 899.1188635137602, 916.7524768312518, 969.8205831921117, 4546.909061818745, 4933.728644924492, 5049.722265275445, 5056.721880296545, 5061.670991385622, 5142.460041695568, 5579.944200558024, 5591.7483713233005, 5604.355499117653, 5632.408597097327, 4100.138970816162, 4472.8019466081905, 4629.65277604181, 4813.78337974785, 5057.266696329085, 2290.9496001079274, 2497.8251522393257, 2513.7234904160528, 2787.8466684332557, 2854.557543580748, 4502.527234640381, 5066.897237646409, 5374.5700343972385, 5512.0, 5569.721513924369, 4888.755562221946, 4895.412550493964, 5325.227841962971, 5502.724863756796, 5577.888442231133, 4793.712377257376, 4937.925931111036, 5062.797488100435, 5398.001369746609, 5578.804741834017, 4478.9552104478535, 5297.788088476572, 5385.872580330826, 5432.18517222404, 5504.972475137599, 4231.576842315744, 4715.6796097092865, 4789.473157952692, 5147.2682305328335, 5267.394249661338, 4753.477117517001, 5005.173111814234, 5518.613697041169, 5578.972105139448, 5583.497485226349, 3456.4469684850305, 3513.118004604875, 3687.8156092194745, 3828.578856325746, 3876.2635099331037, 5123.410807757155, 5127.0, 5256.39551451588, 5332.9733351333, 5367.329083864517, 4308.0, 4604.608608268335, 4613.5847773700525, 4765.547273009059, 5088.542031217208, 4608.446986361658, 4649.5350464953235, 4790.473047964651, 4953.405591329064, 5191.949180073774, 4857.081030039524, 4985.302057711996, 5017.896062866163, 5052.8484145475695, 5128.743562821918, 4729.763511824463, 5044.621653375938, 5200.011997720421, 5311.468853114658, 5365.1222072274395, 4408.625266852354, 4757.763850990029, 4948.727819969792, 5386.118426641728, 5397.757100930389], "value": 3920.6664586684306, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.2715103443448, 715.9976033553029, 781.4803155901326, 811.3468657730529, 828.6602492977878, 16672.666546669076, 17224.655506889856, 17497.30010799568, 17501.037442940633, 17598.736018959728, 15643.06141631503, 16150.676986460263, 16329.510114696548, 16716.2477688504, 16769.91615041926, 857.9356548258875, 871.9520426376563, 879.6129702930722, 892.7991201979568, 895.6596493332524, 694.1080711285998, 884.7743825324533, 884.8053428245776, 885.6501681835704, 885.6811547842772, 745.2324106170653, 903.9683611073614, 933.2580598424236, 935.2424536125726, 938.8497840345547, 924.1128516624049, 924.9306302027375, 924.9676261330821, 925.047201382577, 925.144241576539, 520.8333333333306, 686.7836631461074, 689.0422312984955, 690.0718533572337, 690.8549204667027, 11539.711507212303, 12888.613341599766, 14970.401183952737, 15536.06783592988, 15564.922175389163, 13956.0, 14000.719985600334, 14041.368138433692, 14071.0, 14073.437062517487, 11848.407579620984, 12130.575429859913, 12276.570320038845, 12290.016798656176, 12537.373131343396, 3631.7094632429344, 3664.6885014773775, 3820.4842346283176, 3882.9805850970847, 3889.0, 90425.54787226088, 98334.03331933393, 103175.48412257891, 103508.0, 104461.4776926118, 10402.0, 10680.679579612624, 10910.890891091147, 10968.780624387467, 11245.83131253032, 21459.78540214609, 22098.22656207025, 22277.66583501249, 22283.654687335813, 22330.106795728312, 7676.232376762279, 7703.922960770321, 7731.226877312336, 7779.222077792176, 7950.165232650604, 4253.319468885025, 4361.5856493633055, 4366.3013917773005, 4423.734575925455, 4424.269995450714, 3566.6968307694137, 3670.871519496858, 3673.81630918453, 3683.2265224303196, 3689.7048236141045, 635.3709827270968, 637.003090163892, 641.3330136657823, 643.9291677915426, 647.779754883342, 692.4322055914203, 703.1351437731619, 704.8222331266454, 712.9108861392326, 719.9208087110413, 9006.423875821705, 9659.855102173473, 9721.173700235418, 9734.853977190347, 10659.84010239847, 593.6022864680674, 676.2485064526173, 695.2352412346379, 702.537768847217, 712.3161764705941, 4866.683665561727, 5012.8496145115705, 5024.924626130611, 5027.723475208899, 5033.993201359743, 90867.0, 91820.54089729508, 97729.0, 98007.0, 99888.00111998932, 76079.61960190164, 81242.0, 89808.10191898128, 90798.09201907896, 92100.86890442047, 74396.62801686065, 74660.62669686617, 74803.0, 75561.86657200036, 75663.62168189124, 9566.378185418203, 10619.044286014296, 10894.945525272324, 11104.666859994213, 11214.794536971316, 49293.50706492889, 50355.0, 50570.74714626403, 51981.480185197666, 52123.739381302854, 68586.314136858, 71211.93182102169, 71724.0, 72639.27360726536, 72843.27156728577, 11081.722956926142, 12945.54690585825, 13000.154989925624, 13031.47874085026, 13195.934020329836, 13905.0, 14908.18005009735, 14966.251687415803, 15101.47144849925, 15601.063936163866, 11540.763086966264, 13542.526011589547, 13774.793378099537, 13919.582412527236, 14363.56909292723, 13509.59471215865, 13743.656408589864, 13903.652408689459, 14317.284135793378, 14361.712765744836, 13436.731265374834, 13603.319834008458, 13617.319134043457, 13632.318384080558, 13656.795148072588, 9421.434713917188, 10661.466926653482, 11433.371164585695, 11536.25014374063, 11874.109441791727, 10812.297200681931, 10937.890621094006, 11037.724056898642, 11061.612843550438, 11071.612493562687], "value": 7592.917888126541, "unit": "score"}], "name": "dromaeo_css", "value": 7182.46519930205, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 113.0, 161.0, 164.0, 114.0, 112.0, 114.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 113.0, 174.0, 112.0, 111.0, 109.0, 110.0, 129.0, 111.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 180.0, 185.0, 186.0, 183.0, 180.0, 182.0, 184.0, 182.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 87.0, 60.0, 59.0, 60.0, 58.0, 73.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 85.0, 70.0, 94.0, 99.0, 116.0, 68.0, 68.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 86.0, 83.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 108.0, 149.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 130.0, 91.0, 85.0, 84.0, 87.0, 87.0, 86.0, 110.0, 128.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 75.0, 72.0, 71.0, 69.0, 69.0, 69.0, 68.0, 69.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 86.0, 85.0, 86.0, 88.0, 88.0, 87.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 156.0, 138.0, 145.0, 144.0, 146.0, 131.0, 140.0, 180.0, 153.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 229.0, 183.0, 170.0, 170.0, 227.0, 172.0, 173.0, 177.0, 173.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 67.0, 63.0, 61.0, 68.0, 66.0, 67.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1477.4, "alertThreshold": 2.0}]} 04:31:25 INFO - Return code: 0 04:31:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:31:25 INFO - # TBPL SUCCESS # 04:31:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:31:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:31:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:31:25 INFO - 2017-01-13 04:31:25.635 screenresolution[1648:9641] starting screenresolution argv=screenresolution get 04:31:25 INFO - 2017-01-13 04:31:25.638 screenresolution[1648:9641] Display 0: 1600x1200x32@60 04:31:25 INFO - 2017-01-13 04:31:25.644 screenresolution[1649:9644] starting screenresolution argv=screenresolution list 04:31:25 INFO - Available Modes on Display 0 04:31:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:31:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:31:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:31:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:31:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:31:25 INFO - Intel Iris: 04:31:25 INFO - Chipset Model: Intel Iris 04:31:25 INFO - Type: GPU 04:31:25 INFO - Bus: Built-In 04:31:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:31:25 INFO - Vendor: Intel (0x8086) 04:31:25 INFO - Device ID: 0x0a2e 04:31:25 INFO - Revision ID: 0x0009 04:31:25 INFO - Displays: 04:31:25 INFO - Display: 04:31:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:31:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:31:25 INFO - Display Serial Number: 1600x1200 60 04:31:25 INFO - Main Display: Yes 04:31:25 INFO - Mirror: Off 04:31:25 INFO - Online: Yes 04:31:25 INFO - Rotation: Supported 04:31:25 INFO - Return code: 0 04:31:25 INFO - Running post-action listener: _resource_record_post_action 04:31:25 INFO - [mozharness: 2017-01-13 12:31:25.880635Z] Finished run-tests step (success) 04:31:25 INFO - Running post-run listener: _resource_record_post_run 04:31:25 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37676032; Write bytes: 552324608; Read time: 237; Write time: 1685 04:31:25 INFO - TinderboxPrint: CPU usage
53.9% 04:31:25 INFO - TinderboxPrint: I/O read bytes / time
37,676,032 / 237 04:31:25 INFO - TinderboxPrint: I/O write bytes / time
552,324,608 / 1,685 04:31:25 INFO - TinderboxPrint: CPU idle
2,216.2 (46.1%) 04:31:25 INFO - TinderboxPrint: CPU system
962.2 (20.0%) 04:31:25 INFO - TinderboxPrint: CPU user
1,627.2 (33.9%) 04:31:25 INFO - TinderboxPrint: Swap in / out
289,087,488 / 0 04:31:25 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250139136; Write bytes: 242405376; Read time: 18199; Write time: 517 04:31:25 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34321408; Write bytes: 298466816; Read time: 213; Write time: 1130 04:31:26 INFO - Running post-run listener: _upload_blobber_files 04:31:26 INFO - Blob upload gear active. 04:31:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:31:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:31:26 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'] 04:31:26 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 04:31:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:31:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:31:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:28 INFO - (blobuploader) - INFO - Done attempting. 04:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:31:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:29 INFO - (blobuploader) - INFO - Done attempting. 04:31:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:31:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:31:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:30 INFO - (blobuploader) - INFO - Done attempting. 04:31:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:31:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:32 INFO - (blobuploader) - INFO - Done attempting. 04:31:32 INFO - (blobuploader) - INFO - Iteration through files over. 04:31:32 INFO - Return code: 0 04:31:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:31:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:31:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c3c3a9de4e6637c0a02ff7491ec87b77156480f564b7871a612ad5a43e01a6516868b765db05e6a355e4fd35968c56a22dea0c7ebba57493166a7215d8a8953", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef38487d9f738ecb73d761b9d1a44c0ea26c7ed6bcbebe8b637f41624f95055fa1f3be849d3fcfc718e77a9de6d59e6fe7a7ef1f0a1c3670243504a0b896f21a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b6258f9c0b02f61524ab90bf977dc8040bfcc2f106ae3efcf2dddfce1b9d75702546c5ec689cdfe41e19ee5188c49948e6f6091f4c99c60eb7eec8c241bdc281", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d74b6b16fa58e5c84c3daab45f23d760d4f4a00d407bab7c300fa75ea3ea0c6eb34a66be927e4f1c0ce4f75182bb1b5e8436be79c48ee5551ac1dd5218a495ac"} 04:31:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:31:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:31:32 INFO - Contents: 04:31:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c3c3a9de4e6637c0a02ff7491ec87b77156480f564b7871a612ad5a43e01a6516868b765db05e6a355e4fd35968c56a22dea0c7ebba57493166a7215d8a8953", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef38487d9f738ecb73d761b9d1a44c0ea26c7ed6bcbebe8b637f41624f95055fa1f3be849d3fcfc718e77a9de6d59e6fe7a7ef1f0a1c3670243504a0b896f21a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b6258f9c0b02f61524ab90bf977dc8040bfcc2f106ae3efcf2dddfce1b9d75702546c5ec689cdfe41e19ee5188c49948e6f6091f4c99c60eb7eec8c241bdc281", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d74b6b16fa58e5c84c3daab45f23d760d4f4a00d407bab7c300fa75ea3ea0c6eb34a66be927e4f1c0ce4f75182bb1b5e8436be79c48ee5551ac1dd5218a495ac"} 04:31:32 INFO - Running post-run listener: copy_logs_to_upload_dir 04:31:32 INFO - Copying logs to upload dir... 04:31:32 INFO - mkdir: /builds/slave/test/build/upload/logs 04:31:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.628947 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-13 04:31:32.546115) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 04:31:32.549477) ========= 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.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c3c3a9de4e6637c0a02ff7491ec87b77156480f564b7871a612ad5a43e01a6516868b765db05e6a355e4fd35968c56a22dea0c7ebba57493166a7215d8a8953", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef38487d9f738ecb73d761b9d1a44c0ea26c7ed6bcbebe8b637f41624f95055fa1f3be849d3fcfc718e77a9de6d59e6fe7a7ef1f0a1c3670243504a0b896f21a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b6258f9c0b02f61524ab90bf977dc8040bfcc2f106ae3efcf2dddfce1b9d75702546c5ec689cdfe41e19ee5188c49948e6f6091f4c99c60eb7eec8c241bdc281", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d74b6b16fa58e5c84c3daab45f23d760d4f4a00d407bab7c300fa75ea3ea0c6eb34a66be927e4f1c0ce4f75182bb1b5e8436be79c48ee5551ac1dd5218a495ac"} build_url:https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/OVf6EC3VTl6uQg6I_NwBBQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c3c3a9de4e6637c0a02ff7491ec87b77156480f564b7871a612ad5a43e01a6516868b765db05e6a355e4fd35968c56a22dea0c7ebba57493166a7215d8a8953", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef38487d9f738ecb73d761b9d1a44c0ea26c7ed6bcbebe8b637f41624f95055fa1f3be849d3fcfc718e77a9de6d59e6fe7a7ef1f0a1c3670243504a0b896f21a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b6258f9c0b02f61524ab90bf977dc8040bfcc2f106ae3efcf2dddfce1b9d75702546c5ec689cdfe41e19ee5188c49948e6f6091f4c99c60eb7eec8c241bdc281", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d74b6b16fa58e5c84c3daab45f23d760d4f4a00d407bab7c300fa75ea3ea0c6eb34a66be927e4f1c0ce4f75182bb1b5e8436be79c48ee5551ac1dd5218a495ac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 04:31:32.584393) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:31:32.584751) ========= 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.W4tVLvEAur/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.B4oqMxioQc/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005121 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 04:31:32.634399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 04:31:32.634782) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 04:31:32.635132) ========= ========= Total master_lag: 0.39 =========