builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0223 starttime: 1475682730.38 results: success (0) buildid: 20161005063010 builduid: 379c2bed0c964f3aa773525b0d7174d4 revision: cb48a92d0427b50fd18801f9a7f6607b46a7d38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.380289) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.380705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.380995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.408757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.409147) ========= 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.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 --2016-10-05 08:52:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.7M=0s 2016-10-05 08:52:10 (38.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.542479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.542921) ========= 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.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.035809 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:10.598963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 08:52:10.599318) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb48a92d0427b50fd18801f9a7f6607b46a7d38a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cb48a92d0427b50fd18801f9a7f6607b46a7d38a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 2016-10-05 08:52:10,678 truncating revision to first 12 chars 2016-10-05 08:52:10,678 Setting DEBUG logging. 2016-10-05 08:52:10,678 attempt 1/10 2016-10-05 08:52:10,678 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb48a92d0427?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 08:52:11,731 unpacking tar archive at: mozilla-central-cb48a92d0427/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 08:52:11.950168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:11.950494) ========= script_repo_revision: cb48a92d0427b50fd18801f9a7f6607b46a7d38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:11.950903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:11.951172) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 08:52:11.969566) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-05 08:52:11.970013) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:12 INFO - MultiFileLogger online at 20161005 08:52:12 in /builds/slave/test 08:52:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:52:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:12 INFO - {'append_to_log': False, 08:52:12 INFO - 'base_work_dir': '/builds/slave/test', 08:52:12 INFO - 'blob_upload_branch': 'Firefox', 08:52:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:12 INFO - 'branch': 'Firefox', 08:52:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:12 INFO - 'config_files': ('talos/mac_config.py',), 08:52:12 INFO - 'default_actions': ('clobber', 08:52:12 INFO - 'read-buildbot-config', 08:52:12 INFO - 'download-and-extract', 08:52:12 INFO - 'populate-webroot', 08:52:12 INFO - 'create-virtualenv', 08:52:12 INFO - 'install', 08:52:12 INFO - 'run-tests'), 08:52:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:12 INFO - 'download_minidump_stackwalk': True, 08:52:12 INFO - 'download_symbols': 'ondemand', 08:52:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:12 INFO - 'installer_path': 'installer.exe', 08:52:12 INFO - 'log_level': 'info', 08:52:12 INFO - 'log_name': 'talos', 08:52:12 INFO - 'log_to_console': True, 08:52:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:12 INFO - 'opt_config_files': (), 08:52:12 INFO - 'pip_index': False, 08:52:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:12 INFO - 'cmd': ('bash', 08:52:12 INFO - '-c', 08:52:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:12 INFO - 'enabled': True, 08:52:12 INFO - 'halt_on_failure': False, 08:52:12 INFO - 'name': 'check_screen_resolution'},), 08:52:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:12 INFO - 'cmd': ('bash', 08:52:12 INFO - '-c', 08:52:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:12 INFO - 'enabled': True, 08:52:12 INFO - 'halt_on_failure': False, 08:52:12 INFO - 'name': 'check_screen_resolution'},), 08:52:12 INFO - 'run_cmd_checks_enabled': True, 08:52:12 INFO - 'sps_profile': False, 08:52:12 INFO - 'sps_profile_interval': 0, 08:52:12 INFO - 'suite': 'dromaeojs', 08:52:12 INFO - 'system_bits': '32', 08:52:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:12 INFO - 'title': 't-yosemite-r7-0223', 08:52:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:12 INFO - 'use_talos_json': True, 08:52:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:12 INFO - 'work_dir': 'build'} 08:52:12 INFO - [mozharness: 2016-10-05 15:52:12.171971Z] Running clobber step. 08:52:12 INFO - Running pre-action listener: _resource_record_pre_action 08:52:12 INFO - Running main action method: clobber 08:52:12 INFO - rmtree: /builds/slave/test/build 08:52:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:14 INFO - Running post-action listener: _resource_record_post_action 08:52:14 INFO - [mozharness: 2016-10-05 15:52:14.226148Z] Finished clobber step (success) 08:52:14 INFO - [mozharness: 2016-10-05 15:52:14.226272Z] Running read-buildbot-config step. 08:52:14 INFO - Running pre-action listener: _resource_record_pre_action 08:52:14 INFO - Running main action method: read_buildbot_config 08:52:14 INFO - Using buildbot properties: 08:52:14 INFO - { 08:52:14 INFO - "project": "", 08:52:14 INFO - "product": "firefox", 08:52:14 INFO - "script_repo_revision": "production", 08:52:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:52:14 INFO - "repository": "", 08:52:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:52:14 INFO - "buildid": "20161005063010", 08:52:14 INFO - "pgo_build": "False", 08:52:14 INFO - "basedir": "/builds/slave/test", 08:52:14 INFO - "buildnumber": 40, 08:52:14 INFO - "slavename": "t-yosemite-r7-0223", 08:52:14 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:52:14 INFO - "platform": "yosemite_r7", 08:52:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:52:14 INFO - "branch": "mozilla-central", 08:52:14 INFO - "repo_path": "mozilla-central", 08:52:14 INFO - "revision": "cb48a92d0427b50fd18801f9a7f6607b46a7d38a", 08:52:14 INFO - "stage_platform": "macosx64", 08:52:14 INFO - "builduid": "379c2bed0c964f3aa773525b0d7174d4", 08:52:14 INFO - "slavebuilddir": "test" 08:52:14 INFO - } 08:52:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:52:14 INFO - Running post-action listener: _resource_record_post_action 08:52:14 INFO - [mozharness: 2016-10-05 15:52:14.229596Z] Finished read-buildbot-config step (success) 08:52:14 INFO - [mozharness: 2016-10-05 15:52:14.229717Z] Running download-and-extract step. 08:52:14 INFO - Running pre-action listener: _resource_record_pre_action 08:52:14 INFO - Running main action method: download_and_extract 08:52:14 INFO - mkdir: /builds/slave/test/build/tests 08:52:14 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')]} 08:52:14 INFO - trying https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:52:14 INFO - Downloading https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:52:15 INFO - Downloaded 1199 bytes. 08:52:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:52:15 INFO - Using the following test package requirements: 08:52:15 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:52:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:52:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:52:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'jsshell-mac.zip'], 08:52:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:52:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:52:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:52:15 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:52:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:52:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:52:15 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:52:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:52:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:52:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:15 INFO - Fetch https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:52:15 INFO - Http code: 200 08:52:15 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 08:52:15 INFO - x-amz-cf-id: dlole5uO-YTsVwwA3pEw4cVUT52ym3p-FV9Yarj5g4RLo2xbxlggmA== 08:52:15 INFO - x-amz-version-id: vxd6R3LALAGqHA62fkiy0Oik8t9xrFVW 08:52:16 INFO - Expected file size: 16476982 08:52:16 INFO - Obtained file size: 16476982 08:52:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:52:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:52:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:22 INFO - Fetch https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:52:23 INFO - Http code: 200 08:52:23 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:52:23 INFO - x-amz-cf-id: Mlpryc2GfkMYnTrd3WDflCBeIoPr-yCP6q9sV_BAckaXWBZSwLFiLQ== 08:52:23 INFO - x-amz-version-id: vxd6R3LALAGqHA62fkiy0Oik8t9xrFVW 08:52:23 INFO - Expected file size: 16476982 08:52:23 INFO - Obtained file size: 16476982 08:52:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:52:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:52:29 INFO - Fetch https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:52:31 INFO - Http code: 200 08:52:31 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 08:52:31 INFO - x-amz-cf-id: HyGYal0rzQ2MOMG_VqdjqkczBo9Bzs59tuz7GNtSQbTSrJ0rGQ3x2A== 08:52:31 INFO - x-amz-version-id: ze3NTJV.xF5cHyOk9ds209ygLI3Se0fS 08:52:31 INFO - Expected file size: 14409006 08:52:31 INFO - Obtained file size: 14409006 08:52:31 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')]} 08:52:31 INFO - trying https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:52:31 INFO - Downloading https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:52:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:52:34 INFO - Downloaded 111649679 bytes. 08:52:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:52:34 INFO - mkdir: /builds/slave/test/properties 08:52:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:34 INFO - Contents: 08:52:34 INFO - build_url:https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:52:35 INFO - Running post-action listener: _resource_record_post_action 08:52:35 INFO - Running post-action listener: set_extra_try_arguments 08:52:35 INFO - [mozharness: 2016-10-05 15:52:35.570245Z] Finished download-and-extract step (success) 08:52:35 INFO - [mozharness: 2016-10-05 15:52:35.570373Z] Running populate-webroot step. 08:52:35 INFO - Running pre-action listener: _resource_record_pre_action 08:52:35 INFO - Running main action method: populate_webroot 08:52:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:35 INFO - '--tppagecycles', 08:52:35 INFO - '7'], 08:52:35 INFO - 'tests': ['tsvgm']}}, 08:52:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['damp', 'tps']}, 08:52:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'tests': ['damp', 'tps']}, 08:52:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['dromaeo_dom']}, 08:52:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['basic_compositor_video']}, 08:52:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['a11yr', 08:52:35 INFO - 'ts_paint', 08:52:35 INFO - 'tpaint', 08:52:35 INFO - 'sessionrestore', 08:52:35 INFO - 'sessionrestore_no_auto_restore', 08:52:35 INFO - 'tabpaint']}, 08:52:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['tsvgx', 08:52:35 INFO - 'tsvgr_opacity', 08:52:35 INFO - 'tart', 08:52:35 INFO - 'tscrollx', 08:52:35 INFO - 'cart']}, 08:52:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:35 INFO - 'tsvgr_opacity', 08:52:35 INFO - 'tart', 08:52:35 INFO - 'tscrollx', 08:52:35 INFO - 'cart']}, 08:52:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'talos_options': ['--disable-e10s'], 08:52:35 INFO - 'tests': ['tp5o']}, 08:52:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'tests': ['tp5o']}, 08:52:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'talos_options': ['--disable-e10s', 08:52:35 INFO - '--xperf_path', 08:52:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:35 INFO - 'tests': ['tp5n']}, 08:52:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:52:35 INFO - 'talos_options': ['--xperf_path', 08:52:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:35 INFO - 'tests': ['tp5n']}}, 08:52:35 INFO - 'talos.zip': {'path': '', 08:52:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:35 INFO - Running post-action listener: _resource_record_post_action 08:52:35 INFO - [mozharness: 2016-10-05 15:52:35.589175Z] Finished populate-webroot step (success) 08:52:35 INFO - [mozharness: 2016-10-05 15:52:35.589289Z] Running create-virtualenv step. 08:52:35 INFO - Running pre-action listener: _resource_record_pre_action 08:52:35 INFO - Running main action method: create_virtualenv 08:52:35 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:35 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 08:52:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:35 INFO - Using real prefix '/tools/python27' 08:52:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:39 INFO - Installing pip.................done. 08:52:39 INFO - Return code: 0 08:52:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:39 INFO - Reading from file tmpfile_stdout 08:52:39 INFO - Output received: 08:52:39 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) 08:52:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:39 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')]} 08:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:39 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')]} 08:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:39 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:39 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 08:52:39 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 08:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:39 INFO - 'HOME': '/Users/cltbld', 08:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:39 INFO - 'LOGNAME': 'cltbld', 08:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:39 INFO - 'MOZ_NO_REMOTE': '1', 08:52:39 INFO - 'NO_EM_RESTART': '1', 08:52:39 INFO - 'PAGER': '/bin/cat', 08:52:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:39 INFO - 'PWD': '/builds/slave/test', 08:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:39 INFO - 'SHELL': '/bin/bash', 08:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:39 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:39 INFO - 'USER': 'cltbld', 08:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:39 INFO - 'XPC_FLAGS': '0x0', 08:52:39 INFO - 'XPC_SERVICE_NAME': '0', 08:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:39 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 08:52:39 INFO - Cleaning up... 08:52:39 INFO - Return code: 0 08:52:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:39 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')]} 08:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:39 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')]} 08:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:39 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:39 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 08:52:39 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 08:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:39 INFO - 'HOME': '/Users/cltbld', 08:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:39 INFO - 'LOGNAME': 'cltbld', 08:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:39 INFO - 'MOZ_NO_REMOTE': '1', 08:52:39 INFO - 'NO_EM_RESTART': '1', 08:52:39 INFO - 'PAGER': '/bin/cat', 08:52:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:39 INFO - 'PWD': '/builds/slave/test', 08:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:39 INFO - 'SHELL': '/bin/bash', 08:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:39 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:39 INFO - 'USER': 'cltbld', 08:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:39 INFO - 'XPC_FLAGS': '0x0', 08:52:39 INFO - 'XPC_SERVICE_NAME': '0', 08:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:40 INFO - Downloading/unpacking psutil>=3.1.1 08:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:43 INFO - Installing collected packages: psutil 08:52:43 INFO - Running setup.py install for psutil 08:52:43 INFO - building 'psutil._psutil_osx' extension 08:52:43 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 INFO - building 'psutil._psutil_posix' extension 08:52:44 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 08:52:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:52:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:52:44 INFO - ^ 08:52:44 INFO - 1 warning generated. 08:52:44 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 08:52:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:44 INFO - Successfully installed psutil 08:52:44 INFO - Cleaning up... 08:52:44 INFO - Return code: 0 08:52:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:52:44 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')]} 08:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:44 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')]} 08:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:44 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:44 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 08:52:44 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 08:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:44 INFO - 'HOME': '/Users/cltbld', 08:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:44 INFO - 'LOGNAME': 'cltbld', 08:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:44 INFO - 'MOZ_NO_REMOTE': '1', 08:52:44 INFO - 'NO_EM_RESTART': '1', 08:52:44 INFO - 'PAGER': '/bin/cat', 08:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:44 INFO - 'PWD': '/builds/slave/test', 08:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:44 INFO - 'SHELL': '/bin/bash', 08:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:44 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:44 INFO - 'USER': 'cltbld', 08:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:44 INFO - 'XPC_FLAGS': '0x0', 08:52:44 INFO - 'XPC_SERVICE_NAME': '0', 08:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:52:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:52:47 INFO - Installing collected packages: mozsystemmonitor 08:52:47 INFO - Running setup.py install for mozsystemmonitor 08:52:47 INFO - Successfully installed mozsystemmonitor 08:52:47 INFO - Cleaning up... 08:52:47 INFO - Return code: 0 08:52:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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')]} 08:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:47 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:47 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 08:52:47 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 08:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:47 INFO - 'HOME': '/Users/cltbld', 08:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:47 INFO - 'LOGNAME': 'cltbld', 08:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:47 INFO - 'MOZ_NO_REMOTE': '1', 08:52:47 INFO - 'NO_EM_RESTART': '1', 08:52:47 INFO - 'PAGER': '/bin/cat', 08:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:47 INFO - 'PWD': '/builds/slave/test', 08:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:47 INFO - 'SHELL': '/bin/bash', 08:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:47 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:47 INFO - 'USER': 'cltbld', 08:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:47 INFO - 'XPC_FLAGS': '0x0', 08:52:47 INFO - 'XPC_SERVICE_NAME': '0', 08:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:47 INFO - Downloading/unpacking jsonschema==2.5.1 08:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:52:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:50 INFO - Downloading functools32-3.2.3-2.tar.gz 08:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:52:50 INFO - warning: no files found matching '*.txt' 08:52:50 INFO - no previously-included directories found matching 'build' 08:52:50 INFO - no previously-included directories found matching 'dist' 08:52:50 INFO - no previously-included directories found matching '.git*' 08:52:50 INFO - Installing collected packages: jsonschema, functools32 08:52:50 INFO - Running setup.py install for functools32 08:52:51 INFO - warning: no files found matching '*.txt' 08:52:51 INFO - no previously-included directories found matching 'build' 08:52:51 INFO - no previously-included directories found matching 'dist' 08:52:51 INFO - no previously-included directories found matching '.git*' 08:52:51 INFO - Successfully installed jsonschema functools32 08:52:51 INFO - Cleaning up... 08:52:51 INFO - Return code: 0 08:52:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:51 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:51 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 08:52:51 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 08:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:51 INFO - 'HOME': '/Users/cltbld', 08:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:51 INFO - 'LOGNAME': 'cltbld', 08:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:51 INFO - 'MOZ_NO_REMOTE': '1', 08:52:51 INFO - 'NO_EM_RESTART': '1', 08:52:51 INFO - 'PAGER': '/bin/cat', 08:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:51 INFO - 'PWD': '/builds/slave/test', 08:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:51 INFO - 'SHELL': '/bin/bash', 08:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:51 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:51 INFO - 'USER': 'cltbld', 08:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:51 INFO - 'XPC_FLAGS': '0x0', 08:52:51 INFO - 'XPC_SERVICE_NAME': '0', 08:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:52:51 INFO - Cleaning up... 08:52:51 INFO - Return code: 0 08:52:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:51 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:51 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 08:52:51 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 08:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:51 INFO - 'HOME': '/Users/cltbld', 08:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:51 INFO - 'LOGNAME': 'cltbld', 08:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:51 INFO - 'MOZ_NO_REMOTE': '1', 08:52:51 INFO - 'NO_EM_RESTART': '1', 08:52:51 INFO - 'PAGER': '/bin/cat', 08:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:51 INFO - 'PWD': '/builds/slave/test', 08:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:51 INFO - 'SHELL': '/bin/bash', 08:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:51 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:51 INFO - 'USER': 'cltbld', 08:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:51 INFO - 'XPC_FLAGS': '0x0', 08:52:51 INFO - 'XPC_SERVICE_NAME': '0', 08:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:51 INFO - Downloading/unpacking blobuploader==1.2.4 08:52:51 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 08:52:51 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 08:52:51 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 08:52:51 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 08:52:51 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 08:52:51 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 08:52:54 INFO - Downloading blobuploader-1.2.4.tar.gz 08:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:52:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:52:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:54 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 08:52:55 INFO - Downloading docopt-0.6.1.tar.gz 08:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:52:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:52:55 INFO - Running setup.py install for blobuploader 08:52:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:52:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:52:55 INFO - Running setup.py install for requests 08:52:56 INFO - Running setup.py install for docopt 08:52:56 INFO - Successfully installed blobuploader requests docopt 08:52:56 INFO - Cleaning up... 08:52:56 INFO - Return code: 0 08:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:56 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:52:56 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 08:52:56 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 08:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:56 INFO - 'HOME': '/Users/cltbld', 08:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:56 INFO - 'LOGNAME': 'cltbld', 08:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:56 INFO - 'MOZ_NO_REMOTE': '1', 08:52:56 INFO - 'NO_EM_RESTART': '1', 08:52:56 INFO - 'PAGER': '/bin/cat', 08:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:56 INFO - 'PWD': '/builds/slave/test', 08:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:56 INFO - 'SHELL': '/bin/bash', 08:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:52:56 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:56 INFO - 'USER': 'cltbld', 08:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:56 INFO - 'XPC_FLAGS': '0x0', 08:52:56 INFO - 'XPC_SERVICE_NAME': '0', 08:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:56 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-AxdQVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:56 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-qhWco4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:56 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-fjw3Bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:56 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-gOXIP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-y0bZnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-6mgwnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-DTpVdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-hA0VCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-DZn86j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ojEHim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-CFd1eQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-DRMPf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-mHGM3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:57 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-jDuWvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:58 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-emnb1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:58 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-AVBZj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:58 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-5iflls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:52:58 INFO - Running setup.py install for manifestparser 08:52:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:52:58 INFO - Running setup.py install for mozcrash 08:52:58 INFO - Running setup.py install for mozdebug 08:52:58 INFO - Running setup.py install for mozdevice 08:52:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:52:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:52:58 INFO - Running setup.py install for mozfile 08:52:59 INFO - Running setup.py install for mozhttpd 08:52:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Running setup.py install for mozinfo 08:52:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Running setup.py install for mozInstall 08:52:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Running setup.py install for mozleak 08:52:59 INFO - Running setup.py install for mozlog 08:52:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:52:59 INFO - Running setup.py install for moznetwork 08:53:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Running setup.py install for mozprocess 08:53:00 INFO - Running setup.py install for mozprofile 08:53:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Running setup.py install for mozrunner 08:53:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Running setup.py install for mozscreenshot 08:53:00 INFO - Running setup.py install for moztest 08:53:00 INFO - Running setup.py install for mozversion 08:53:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:00 INFO - Cleaning up... 08:53:00 INFO - Return code: 0 08:53:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:00 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')]} 08:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:00 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')]} 08:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:00 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:53:00 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 08:53:00 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 08:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:00 INFO - 'HOME': '/Users/cltbld', 08:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:00 INFO - 'LOGNAME': 'cltbld', 08:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:00 INFO - 'MOZ_NO_REMOTE': '1', 08:53:00 INFO - 'NO_EM_RESTART': '1', 08:53:00 INFO - 'PAGER': '/bin/cat', 08:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:00 INFO - 'PWD': '/builds/slave/test', 08:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:00 INFO - 'SHELL': '/bin/bash', 08:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:53:00 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:00 INFO - 'USER': 'cltbld', 08:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:00 INFO - 'XPC_FLAGS': '0x0', 08:53:00 INFO - 'XPC_SERVICE_NAME': '0', 08:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-hl94AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:01 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-bFncl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-hn7lwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:01 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-BeXSMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-wv2ygg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:01 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-i8lWdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:01 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-cOmStZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:01 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)) 08:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:01 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-IHUDTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-a1GFiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-NDqCm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-lv13Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Fk0BCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-EFJ2jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-RL0hk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-tOn_fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:02 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Z2cKeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 08:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:02 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-lv1QxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:03 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)) 08:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:03 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)) 08:53:03 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:53:03 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:53:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53: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 08:53: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 08:53: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 08:53: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 08:53: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 08:53: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 08:53:05 INFO - Downloading blessings-1.6.tar.gz 08:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:05 INFO - Installing collected packages: blessings 08:53:05 INFO - Running setup.py install for blessings 08:53:05 INFO - Successfully installed blessings 08:53:05 INFO - Cleaning up... 08:53:05 INFO - Return code: 0 08:53:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:06 INFO - Reading from file tmpfile_stdout 08:53:06 INFO - Current package versions: 08:53:06 INFO - blessings == 1.6 08:53:06 INFO - blobuploader == 1.2.4 08:53:06 INFO - docopt == 0.6.1 08:53:06 INFO - functools32 == 3.2.3-2 08:53:06 INFO - jsonschema == 2.5.1 08:53:06 INFO - manifestparser == 1.1 08:53:06 INFO - mozInstall == 1.12 08:53:06 INFO - mozcrash == 0.17 08:53:06 INFO - mozdebug == 0.1 08:53:06 INFO - mozdevice == 0.48 08:53:06 INFO - mozfile == 1.2 08:53:06 INFO - mozhttpd == 0.7 08:53:06 INFO - mozinfo == 0.9 08:53:06 INFO - mozleak == 0.1 08:53:06 INFO - mozlog == 3.4 08:53:06 INFO - moznetwork == 0.27 08:53:06 INFO - mozprocess == 0.23 08:53:06 INFO - mozprofile == 0.28 08:53:06 INFO - mozrunner == 6.12 08:53:06 INFO - mozscreenshot == 0.1 08:53:06 INFO - mozsystemmonitor == 0.3 08:53:06 INFO - moztest == 0.7 08:53:06 INFO - mozversion == 1.4 08:53:06 INFO - psutil == 3.1.1 08:53:06 INFO - requests == 1.2.3 08:53:06 INFO - wsgiref == 0.1.2 08:53:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:06 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 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:53:06 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 08:53:06 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 08:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:06 INFO - 'HOME': '/Users/cltbld', 08:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:06 INFO - 'LOGNAME': 'cltbld', 08:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:06 INFO - 'MOZ_NO_REMOTE': '1', 08:53:06 INFO - 'NO_EM_RESTART': '1', 08:53:06 INFO - 'PAGER': '/bin/cat', 08:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:06 INFO - 'PWD': '/builds/slave/test', 08:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:06 INFO - 'SHELL': '/bin/bash', 08:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:53:06 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:06 INFO - 'USER': 'cltbld', 08:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:06 INFO - 'XPC_FLAGS': '0x0', 08:53:06 INFO - 'XPC_SERVICE_NAME': '0', 08:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 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)) 08:53:07 INFO - Cleaning up... 08:53:07 INFO - Return code: 0 08:53:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f075490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efeb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac3148d4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, '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.emFyTzBNyS/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.WIR4BCRmoX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 08:53: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', 'jsonschema'] in /builds/slave/test/build 08:53: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 jsonschema 08:53:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:53:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:07 INFO - 'HOME': '/Users/cltbld', 08:53:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:07 INFO - 'LOGNAME': 'cltbld', 08:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:07 INFO - 'MOZ_NO_REMOTE': '1', 08:53:07 INFO - 'NO_EM_RESTART': '1', 08:53:07 INFO - 'PAGER': '/bin/cat', 08:53:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:07 INFO - 'PWD': '/builds/slave/test', 08:53:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:07 INFO - 'SHELL': '/bin/bash', 08:53:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:53:07 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:53:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:07 INFO - 'USER': 'cltbld', 08:53:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:07 INFO - 'XPC_FLAGS': '0x0', 08:53:07 INFO - 'XPC_SERVICE_NAME': '0', 08:53:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:08 INFO - Cleaning up... 08:53:08 INFO - Return code: 0 08:53:08 INFO - Running post-action listener: _resource_record_post_action 08:53:08 INFO - Running post-action listener: _start_resource_monitoring 08:53:08 INFO - Starting resource monitoring. 08:53:08 INFO - [mozharness: 2016-10-05 15:53:08.142933Z] Finished create-virtualenv step (success) 08:53:08 INFO - [mozharness: 2016-10-05 15:53:08.143902Z] Running install step. 08:53:08 INFO - Running pre-action listener: _resource_record_pre_action 08:53:08 INFO - Running main action method: install 08:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:08 INFO - Reading from file tmpfile_stdout 08:53:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:08 INFO - Reading from file tmpfile_stdout 08:53:08 INFO - Output received: 08:53:08 INFO - Usage: mozinstall [options] installer 08:53:08 INFO - Options: 08:53:08 INFO - -h, --help show this help message and exit 08:53:08 INFO - -d DEST, --destination=DEST 08:53:08 INFO - Directory to install application into. [default: 08:53:08 INFO - "/builds/slave/test"] 08:53:08 INFO - --app=APP Application being installed. [default: firefox] 08:53:08 INFO - mkdir: /builds/slave/test/build/application 08:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:53:43 INFO - Reading from file tmpfile_stdout 08:53:43 INFO - Output received: 08:53:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:53:43 INFO - Running post-action listener: _resource_record_post_action 08:53:43 INFO - [mozharness: 2016-10-05 15:53:43.097978Z] Finished install step (success) 08:53:43 INFO - [mozharness: 2016-10-05 15:53:43.098154Z] Running run-tests step. 08:53:43 INFO - Running pre-action listener: _resource_record_pre_action 08:53:43 INFO - Running main action method: run_tests 08:53:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:43 INFO - 2016-10-05 08:53:43.112 screenresolution[1640:7548] starting screenresolution argv=screenresolution get 08:53:43 INFO - 2016-10-05 08:53:43.115 screenresolution[1640:7548] Display 0: 1600x1200x32@60 08:53:43 INFO - 2016-10-05 08:53:43.120 screenresolution[1641:7550] starting screenresolution argv=screenresolution list 08:53:43 INFO - Available Modes on Display 0 08:53:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:43 INFO - Intel Iris: 08:53:43 INFO - Chipset Model: Intel Iris 08:53:43 INFO - Type: GPU 08:53:43 INFO - Bus: Built-In 08:53:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:43 INFO - Vendor: Intel (0x8086) 08:53:43 INFO - Device ID: 0x0a2e 08:53:43 INFO - Revision ID: 0x0009 08:53:43 INFO - Displays: 08:53:43 INFO - Display: 08:53:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:43 INFO - Display Serial Number: 1600x1200 60 08:53:43 INFO - Main Display: Yes 08:53:43 INFO - Mirror: Off 08:53:43 INFO - Online: Yes 08:53:43 INFO - Rotation: Supported 08:53:43 INFO - Return code: 0 08:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:43 INFO - Python 2.7.3 08:53:43 INFO - Return code: 0 08:53:43 INFO - grabbing minidump binary from tooltool 08:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:43 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 0x10ee89d30>, '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 0x10ecafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee81b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:43 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 08:53:43 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 08:53:43 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 08:53:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:43 INFO - Return code: 0 08:53:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:53:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:53:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 08:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0223 --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 08:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.emFyTzBNyS/Render', 08:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:43 INFO - 'HOME': '/Users/cltbld', 08:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:43 INFO - 'LOGNAME': 'cltbld', 08:53:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:43 INFO - 'MOZ_NO_REMOTE': '1', 08:53:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:43 INFO - 'NO_EM_RESTART': '1', 08:53:43 INFO - 'PAGER': '/bin/cat', 08:53:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:43 INFO - 'PWD': '/builds/slave/test', 08:53:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:43 INFO - 'SHELL': '/bin/bash', 08:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WIR4BCRmoX/Listeners', 08:53:43 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 08:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:43 INFO - 'USER': 'cltbld', 08:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:43 INFO - 'XPC_FLAGS': '0x0', 08:53:43 INFO - 'XPC_SERVICE_NAME': '0', 08:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 08:53:43 INFO - mozversion application_buildid: 20161005063010 08:53:43 INFO - mozversion application_changeset: cb48a92d0427b50fd18801f9a7f6607b46a7d38a 08:53:43 INFO - mozversion application_display_name: Nightly 08:53:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:43 INFO - mozversion application_name: Firefox 08:53:43 INFO - mozversion application_remotingname: firefox 08:53:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:43 INFO - mozversion application_vendor: Mozilla 08:53:43 INFO - mozversion application_version: 52.0a1 08:53:43 INFO - mozversion platform_buildid: 20161005063010 08:53:43 INFO - mozversion platform_changeset: cb48a92d0427b50fd18801f9a7f6607b46a7d38a 08:53:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:43 INFO - mozversion platform_version: 52.0a1 08:53:43 INFO - using testdate: 1475682823 08:53:43 INFO - actual date: 1475682823 08:53:43 INFO - starting webserver on 'localhost:49283' 08:53:43 INFO - SUITE-START | Running 2 tests 08:53:43 INFO - TEST-START | dromaeo_css 08:53:43 INFO - operating with platform_type : mac_ 08:53:43 INFO - Initialising browser for dromaeo_css test... 08:53:43 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpRfRjGF/profile http://localhost:49283/getInfo.html) 08:53:46 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:53:46 INFO - PROCESS | 1651 | colorDepth:24 08:53:46 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:53:46 INFO - PROCESS | 1651 | __metrics 08:53:46 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:53:46 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:53:46 INFO - TEST-INFO | 1651: exit 0 08:53:46 INFO - Browser initialized. 08:53:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:46 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpRfRjGF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:58 INFO - PROCESS | 1653 | RSS: Main: 235823104 08:53:58 INFO - PROCESS | 1653 | 08:54:50 INFO - PROCESS | 1653 | 1475682890169 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:57:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 08:57:05 INFO - PROCESS | 1653 | RSS: Main: 390819840 08:57:05 INFO - PROCESS | 1653 | 09:00:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 09:00:08 INFO - PROCESS | 1653 | RSS: Main: 372584448 09:00:08 INFO - PROCESS | 1653 | 09:03:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 09:03:11 INFO - PROCESS | 1653 | RSS: Main: 363601920 09:03:11 INFO - PROCESS | 1653 | 09:06:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 09:06:14 INFO - PROCESS | 1653 | RSS: Main: 352772096 09:06:14 INFO - PROCESS | 1653 | 09:09:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 09:09:18 INFO - PROCESS | 1653 | RSS: Main: 328953856 09:09:18 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 09:12:22 INFO - PROCESS | 1653 | RSS: Main: 411832320 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | __start_tp_report 09:12:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:12:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:12:22 INFO - PROCESS | 1653 | |i|pagename|runs| 09:12:22 INFO - PROCESS | 1653 | |0;dojo.html;3283.819389933554;3304.90085297441;3323.451630480971;3341.832908354582;3379.341028499442;5547.72261386931;5607.887842243158;5621.690807005613;5647.830565083043;5667.8583035424135;4616.515265897075;5612.186232996223;5724.685142317171;5727.971360143204;5740.253767010283;4828.517148285178;5142.408623008346;5147.562457191125;5176.378834539861;5186.662866913657;4739.763011849411;5258.211268309765;5264.92102618461;5271.103912334912;5316.308879845615;5111.821086261963;5233.842984710464;5236.581073514109;5242.842714718546;5326.813561525348;5126.641135120506;5143.819966301162;5187.04039752647;5212.270282160501;5222.0861349264105;5100.311457953166;5112.565431938289;5203.791848326063;5214.139666954986;5243.318368612094;5643.8589035274035;5675.318961724578;5680.034394153004;5702.629329093637;5713.742881570298;6031.939680603182;6195.380461953769;6213.285472170713;6217.191765070557;6239.0329498927285;6103.822585931238;6114.114890844268;6241.438270555672;6251.687415629201;6283.403076707751;5761.193432919395;5776.393478684678;5778.624389414675;5785.074388097884;5802.013657678205;6232.594881332699;6390.616563006234;6391.488680905516;6393.392627700361;6395.744170233232;5947.553933454921;6050.939490605126;6124;6127.142200091991;6144.310314663503;6217.751289948442;6246.219222597176;6269.247690277196;6270.31026587075;6276.529260305404;5122.231665250168;5866.73599688015;6221.3156552779155;6232.158658581124;6255.311915689362;6022.668753218615;6073.392660733891;6086.513078953673;6120.632762034204;6147.586055207327;4868.172410690191;4876.366072410564;4881.389826271716;4891.510848915151;4892.804287828447;3670.7614005089586;4190.580941905846;4411.933820992687;4414.646828253796;4445.73325600465;5536.141898005735;5584.357798853102;5608.887822243532;5656.660600363992;5659.377468478464;6063.575549711478;6081;6084.787032453843;6089.756409743561;6094.451499365078;5592.3009623797025;5595.244641973281;5603.607747457639;5632.211490391349;5700.458456446631;5014.774335154927;5317.813876514303;5341.225522299244;5357.464253574689;5437.673739575559;6278.152449419453;6342.587731797326;6346.301906790157;6351.523635727432;6361.936380636227;6232.75068997235;6274.937250627435;6277.18396608438;6304.590201636879;6305;6247.468965137832;6289.49684025286;6293.748250070129;6323.08315294289;6327.96836015817;6097.887666312884;6104.270499843183;6137.079438084323;6160.3223645398075;6161.815145545641;4750.643701722453;6213.720382582705;6244.250689917239;6249.96875015622;6252.562320637512;5855.38518455556;5895.67573783446;5913.2608423947;5995.5803093783015;6075.635461872125;5722.284714410699;5758.539316854558;5778.335491418541;5829.213056237523;5830.679312637845;5646.915296270641;5656.462636049569;5674.57440691958;5680.460356266148;5688.232088668143;5750.079987202028;5786.276715410574;5791.333996590446;5793.768249269982;5797.246357973437;5734.311882574118;5798.043322851851;5811.94188058114;5837.153612726216;5859.794907178229 09:12:22 INFO - PROCESS | 1653 | |1;ext.html;477.45570050142845;506.7213032831943;517.567830861231;525.1282870435078;551.0356875467932;30500.38999220017;38788.22423551531;40503.797481012625;40616.39075413871;42556.57443425565;41519.37720934188;41901.371479427755;41958.79020604893;41998.16003679924;42013.36979945303;9442.197413219885;9615.519224038804;9820.705378838646;9821.705348839547;9833.508324583741;8773.517456539888;9761.170300524429;9765.707028789147;9770.364926279804;9776.120149186572;9707;9771.560279787427;9789.412635241873;9792.706218813448;9801.509924503782;9716.31985760997;9754.365966212245;9771.169450596708;9777.266704997153;9790;9603.90396096044;9685.225181985425;9711.465869377182;9714.368566043255;9718.854217186748;39525;39827.80086099551;39962;39982;39989.60010399888;3321.485169798685;3627.782333060025;3890.513685789276;3901.317269477858;4067.1662309226535;25436.87281563599;29846.403071938657;31225.8438707805;31246.53130203049;31510.89711860074;2815.8873645054173;2993.191838203673;3263.543103965447;3328.6671332866763;3331.3337332533347;481530;483113;484494;485985;494472.5276373631;52577.474225257254;52857.47142528526;52936;52958.73520632449;52987.73506132445;58594;58656;58751.70624146937;58798;58884;15436;15622.531324060297;15636.905416620955;15645.217739112999;15652;12164.148509604243;12202.389880506116;12210.145289829627;12233.388330583613;12241.877581224073;12787.744245115231;15082.416346283702;15105.622359441102;15120;15307.540773776804;5130.7947682093045;5175.922361164586;5901.557383196277;6114.510839132859;6139.079138129317;868;975.6341371985505;984.4487087231173;991.6727479931606;1057.5353135906823;25953.74046259513;26478.6028209577;29415.558766618517;30074.69925300719;30079.398412031638;1037.739146936476;1042.2287507244585;1067.5249513966367;1096.780643871229;1135.9943200283947;14853.405863765545;15091.169985650895;15198.468053618291;15491.922540387452;15532.378704851679;571625;627291;631526;633846;634044;55719.44280557224;55861.16208256879;55869;55941.720291398284;56059;1305.7976013717698;1344.6503908983734;1364.7952807078818;1367.2070199284392;1368.0560413314781;22151;28634.284142896595;29242.976495822542;29911.102666920026;30234.697653023188;47346;47723.04553908833;47917.57277621974;47917.76041119772;48000.03999920052;576970;577157;578095;578102.1094894499;580498;38548.6145138545;38827.2234555313;39292;39443.40834887536;39502;37949.051273718374;42096.15807683768;46128;46660;47340;37807;37843.05392365213;38364.61635383722;39203.2159356817;39410.605893940694;34449.827750861084;37594.436083458226;38551;38589;38923;36791;37116;37424.62575374211;37896.810515948346;38025.619743802206;29655.548168771795;30439.847800760854;32933.34133317368;33271.83364083164;34194.48708269328;24777.513349199107;24926.0029598814;25245.621315680648;25312.202441784117;25384.238472845846 09:12:22 INFO - PROCESS | 1653 | |2;jquery.html;353.4345047923321;367.7039982813835;375.12595652130557;379.3948651900219;403.22983102274645;15354.539363819089;15524.689506209868;17040.829591704096;20694.275700350492;21346.146154153816;12274.079444041688;14739.115653060824;19758.703619445685;20621.587568248626;20818.271360502386;5265.973670131644;5513.9448605514035;5541.5289700375315;5554.638948468357;5563.8887222255535;4197.8530751423705;5483.122700367942;6122.112293717409;6211;6267.091271765591;5990.73041713124;6236.126942228092;6238.9064164037345;6243.875122497548;6244.188255526775;6139.846503837418;6171.598846075036;6174.10475481055;6203.720832562545;6209.813705588839;5732.111522713986;5878.970605146989;5965.194698715662;5990.341062483123;6014.458698717136;13422.395992180376;13570.525031623845;14307.92690548213;25413.11054113116;25708;2121.0985331234165;2188.2122435923056;2251.448395143184;2541.301142185895;2873.227335621115;9519.381240219433;10181.214829030343;10423.374597524173;10529.473526323729;10629.309094908805;1818.399928023749;1821.3443160462223;1889.5937373464772;1909.312647446918;1980.7326010988477;80030.79953800698;120564;122458;124069;125187;11314.717132071599;11390.430478476042;11535.538578456935;11554.364509952033;11737.295762254293;14623.561293161221;14645.560633181021;14651.267436628126;14660.413583456542;14672.633184170267;6934;8177.059638141571;8278.875816862752;8414.747557573282;8435.536045517432;7682.692692292358;7718.3053525183;8069.888016324199;8086.919130808734;8308.958455207685;4137.9861933825805;4205.600467955539;4423.049044455393;4467.977660111678;4537.863864084083;1855.396995976313;1907.8378337841161;1914.4448110048208;1916.559191385989;1917.166032775719;839.8530257204965;873.4279047224078;875.072423231364;904.4708845325426;906.3383729877227;20288.85522743977;21019.789802101783;21101.366958991253;21111;21356.35930922075;930.7905721212672;955.1584037488554;975.2734213011352;1005.6429967361628;1012.549415510091;10063.345882517759;10861.1854110942;10887.332603512032;10896.202126649172;10980.505877235544;567867.8918897236;571258;574126;587929;594152.0292398597;77003.61498192586;77436.83844742333;114738.70522589669;121355.78644213622;128525.3573732144;920;960.490939801906;970.3838062830013;981.7938232971156;983.0955520920627;15730.449434269745;17653.646927061644;18234.817651823312;18527.444176674722;18966.146523406755;47738;48553.514464856315;50535;50882.745586271834;52118.739406302724;60333.69833150982;74674.62662686652;76295.23704762882;76487.55805988633;77156;7705.845883082195;7897.881531776913;8159.510429374256;8164.7958801030445;8405.991281046314;12066.879331206575;12424.130310878152;12547.372631368578;12746.553870614483;13037.804432933703;10734.463276836283;10961.780764384508;11006.834897476385;11064.834027489433;11120.777584448428;10268.64059757905;10699.465026748787;10731.946340268249;10949.945250273699;11162.44187790591;11012.834807477733;11138.220324577525;11207.887921120684;11441.656750297505;11744.647660570197;7881.724139655084;8014.238647328494;8148.918510815053;8269.04905935825;8445.028821685584;8264.586770661563;10768.3538987661;11148.88851111511;11176.161787866105;11269.856330305742 09:12:22 INFO - PROCESS | 1653 | |3;mootools.html;1344.0793056756117;1403.964900877478;1488.8064551608288;1690.780198574185;1899.591587808621;7835.412344074196;8340.45787023843;8346.958265208674;8347.20701533354;8350.248477637011;8532.829343413143;8597.699080532167;8800.91199088009;8911.331650126234;8935.285177185826;2362.492064206195;2388.2118900762794;2391.354334329739;2393.6050551658955;2398.2805158452393;2337.9532409351805;2364.137089962169;2380.9285721428387;2385.2486466763003;2385.2844146755906;2082.7708952015264;2343.097907305691;2357.7524359942217;2371.288613415977;2377.5839228135005;2314.1090680088287;2332.825038122139;2340.1458467659354;2344.5076533927895;2361.8346715729904;2340.8010319122895;2341.1454818990946;2341.555104530134;2342.4846533762543;2344.050659482912;7422.7773166804545;8958.46249225042;8963.641454341818;8973.775655608597;8983.281337492985;4141.730787498803;4809.807607695688;5241.79032838686;5373.731313434352;5374.543163831079;3126.609173853268;3292.703656670911;3383.4079036168573;3389.9830500847584;3399.2351720862857;1019.3985548526331;1074.1943542343242;1082.6373164989739;1097.1058587251364;1100.8899110089008;11713.70715732105;11759.35323557204;11855.288682679067;11864.940675296655;11878.999999999913;4184.2468355695655;4506.301523263899;4659.301104834302;4800.447948485899;4825.927611085836;9304.72085837426;10094.091531762177;10098.596056157818;10104.949475252577;10110.898891010997;4868.099401610713;5259.342582177228;5403.864903377447;5411.621186516984;5464.890702185934;4419.1382680377665;4587.357769912215;4597.494275629678;4756.952430475721;4950.900981980412;3784.564775050849;3802.1825307558456;3943.9802800985813;3946.8421263149726;3950.7827069511454;1252.8997680185564;1272.10952333367;1277.444311724403;1287.0990306785288;1287.3691890973355;770.9922900770921;863.6977058029639;870.2124577257592;909.9808214799335;952.5854106651617;5638.323401191801;5723.656580605094;5799.101139323411;5933.436323549256;6090.177825993523;434.6023388599459;439.29054576858147;440.6562880952862;451.58228638509246;452.3079688077247;3885.7279990400402;3911.882643520699;3912.7847968361443;3920.4903362562686;4029.1538776857187;10275.897241027644;10354.482275886176;10620.734481637866;10761.569537218424;10935.179861510418;10607.469626518643;10830.404327761891;10834.349939003685;10835.133189344833;10849.67450976472;8004.919950800534;8229.25936665691;8322.250997410261;8342.374321925758;8356.832863342699;5050.949490505048;5202.947970520247;5952.553558483044;6033.155358249761;6143.723532440961;9069.047749986421;10002.949985250027;10008.799824003334;10197.694069177936;10240.334378265388;9598.42409455435;10061.625208951931;10075.899241007495;10424.218183636409;10449.791004180026;6932.896006560007;7167.820804479929;7538.924610753822;7662.8084297893;7675.577843218676;7891.723789667334;8089.312408445113;8268.214519620626;8424.536650484037;8503.489790612335;7377.483576149618;7505.437092217996;7556.168821429528;7639;7645.923540764744;7343.48595598303;7395.334419902019;7400.851982960203;7405;7483.102027756704;6178.41305076017;6931.133608298963;7021.262767409348;7028.262032486516;7033.437325014089;4976.676516026447;5036.597072234285;5079.593632509464;5132.076226279156;5147.639665223548;4552.430946131733;4745;4896.2655601660035;5139.717315547681;5231.947680523298 09:12:22 INFO - PROCESS | 1653 | |4;prototype.html;278.6447279718359;303.258532887091;309.92716711572785;317.2006543510354;318.8198667752718;4800.879978000552;5258.7633556489955;5270.894582108355;5350.946490535093;5356;4216.957830421695;4568.565986231311;4593.540645935404;4708.458527269365;4758.2862570614425;3459.290845376692;3972.1857019310974;3976.912121136412;4011.538673052607;4059.5994381938185;3538.7345949053797;3552.4493703476;4020.758754474726;4035.253478106545;4044.4539987101657;3788;3964.6035396460265;3996.42051902474;4004.6172527346184;4037.596240375968;3517.7889326640484;3758.624137586247;3983.203359328146;3997.6801855851754;4015.297322968469;3264.7061764441314;3441.741869359783;3453.620101788801;3478.930421391558;3483.756137070406;4477.574630410106;5130;5198.558122559587;5219.556337711299;5232.92150617741;4387.288957306653;4511.278195488706;4511.8646440606835;4518.683692141552;4582.816687332536;5453.291072160633;5469.890602187974;5478.780848766084;5534.833954981357;5550.417206193332;3744.962550374516;3803.790791506466;3815;3819.5416550014174;3825.9426108608395;4969.627277954168;5487.588430867661;5701.258836351248;5781.392953739839;5808.361080281165;3302.4716045432615;3777.8111094445167;3787.962120378761;3796.822984874698;3818.866339678098;3220.4847224443974;3358.7648864579733;3834.6932245420858;3896.785676787747;3912.7456715313415;3602.7117830573493;4002.0184962203343;4295.6778241632;4511.639068874482;4543.5910768031035;4912.852614421573;5503.642263252876;5531.612787104864;5544.362398324163;5546.334439867242;1266.8345122487333;1298.4676282724038;1319.3139567424885;1322.801579763024;1339.4173534512518;824.3570015388007;849.4096602861076;849.9872501912477;851.429542206723;853.3557164340883;4353.455818022747;4837.274408838703;5001.324821149171;5212.973935130376;5217.556507696889;5083.949160508348;5322.467753224647;5582.7424348347795;5583.720813959286;5593.468620481048;4850.5149485051215;4954.835613630794;5125.1799712045895;5145.970805838847;5189.4032186299055;2358.4575547624145;2585.5733803922517;2751.6010178524;2952.9557056644167;2966;4887.120318342732;5000.549950504472;5509.807156749494;5641.548676105984;5861.633083352845;5445.373782015119;5680.914786278209;5769.596128271065;5795.739191736382;5839.620424672383;5370.355557333067;5870.970645146747;5895.528357731286;5931.703414829328;5973.940260597338;5210.843674689765;5568.331800184003;5587.832365029056;5631.465010823965;5728.914066288926;4963.801447942042;5651.067573850433;5684.147377893184;5708.971455142698;5729.512991395778;5051.221827994515;5538.615346163459;5987.820365389045;6026.216591843032;6034.185384973148;3559.2881423714844;3775.905602359853;3805.6004119567046;3823.751456155341;3826.6556009959236;4636.168713358137;5508.11870100798;5630.0991841305195;5631.746571404215;5639.351474580311;4654.185517534485;5376.8118115865755;5529.253550770755;5550.972245138749;5583.469570390969;5256.583220906288;5399.946000539944;5443.727813609383;5463.4536546346635;5474.795544980098;4209.536950935456;4362.302031674917;5252.606054545848;5347.4652534747775;5372.973135134299;5328.786848526015;5341.786328546815;5372.5433338166695;5521.944780552143;5619.8876022480135;4507.526709695435;5412.810551630674;5468.617196796186;5473.562115030868;5531.064127555484 09:12:22 INFO - PROCESS | 1653 | |5;yui.html;706.8374273916995;756.4893696754689;758.188738050286;764.3235736373312;799.2447137455107;15514.689706205869;15753.212339383028;15766.605834854134;15766.975146615461;15769.92115039426;14171.929140354283;14285.357158927847;14291.785623215661;14292.428302867898;14294.857051429482;686.423404340354;704.2007321689894;710.1797423975316;829.1294141151782;841.5329492131864;862.9482231066156;863.5336918064238;873.0876234335135;875.5359659380539;877.4954401219293;927.7309580221728;928.2666693312275;930.7859192385722;932.0260327957301;933.444600462727;719.5251134251369;743.4164181117851;745.3701622129292;923.4367036107968;923.4966943016072;549.3874330121926;549.8542886135177;666.7533012785252;682.9419499342562;691.325957191739;14955;14991.250437478082;14993.400263989428;15001.3249403776;15030.098194108388;13636.659083522893;13811.861881381157;13978.440862365493;14034.719305613933;14149.314625469522;11172.497237624326;11722.472488738027;12154.662987071415;12396.876031239752;12408.441620127116;4558.8632341029825;4563.133004729124;4623.8150473981;4768.213244814601;4772.188727916227;91050.54474727584;112078.87921120766;113638;116820.99999999916;117311.8268817318;10950;10985.725356865982;11009.06422954058;11119;11234.943825280821;21766.238181663564;21803.890980544995;21809.67285490719;21869.781302186773;21880.78119218819;7847.529148251123;7961.482503637245;8094.797630059295;8103.392245581605;8162.551059691774;3788.8105594720155;4261.70168088237;4452.977735111369;4526.434195725535;4543.182227199136;3353.3796247694254;3496.3881320768764;3496.3881320768764;3497.090756403353;3498.370293347171;629.9338569450233;634.9873002539923;644.2816259870211;657.3163909534031;658.9029266271604;582.9300483941954;605.0533899907479;624.1854380034099;640.091070679636;643.2988043033116;8968.23769979559;9043.954780226057;9136.451812891248;9210.493422861808;9335.439873607469;568.4855206038542;585.871108356161;613.8404014956144;628.9905651415232;643.8937575300023;4406.493253275914;4452.910941781146;4614.907701845944;4756.500567440438;4879.707217566957;90493;125945;147526;148186.25906870398;155792;136285;138027.30986345003;138954.6104538962;139652;143564.28217859054;76925;85533.57233213795;101836;115530;120644.3967780173;9775.315727898978;9780.266480013885;9870.851937220803;9874;9887.258455616002;58243.4175658238;59773;66498.67002660017;67867.66066169637;69607.60784784377;81645.59177204313;83025.16974830175;84415.57792211;85720;94642;13029.609111726664;13132.606021819362;13206.405711742802;13586.38861251266;13742.519011834538;14871.659957211661;14879.181645009196;14953.40186392532;14987.400503980154;15519.068855868683;13380.933095334462;13774.311284435538;13880.236586987812;14307;14442.714170939968;13756.724865502434;13989.790153147507;13990.860091398956;14270.001099923322;14544.781828272373;12525.311107889154;12651.92458640989;13776.724465510833;13925.23411212393;14116.576502704935;11342;11582.420878956187;11607.90460506748;11648.301101933912;11918.940405297919;10687;11317;11715.648530544097;11728.354940478015;11754.353510557 09:12:22 INFO - PROCESS | 1653 | __end_tp_report 09:12:22 INFO - PROCESS | 1653 | __start_cc_report 09:12:22 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1589 09:12:22 INFO - PROCESS | 1653 | __end_cc_report 09:12:22 INFO - PROCESS | 1653 | __startTimestamp1475683942644__endTimestamp 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:12:22 INFO - PROCESS | 1653 | Number of tests: 6 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5652.85 Median:5776.39 stddev:657.10 (11.4%) stddev-sans-first:632.28 09:12:22 INFO - PROCESS | 1653 | Values: 3283.8 3304.9 3323.5 3341.8 3379.3 5547.7 5607.9 5621.7 5647.8 5667.9 4616.5 5612.2 5724.7 5728.0 5740.3 4828.5 5142.4 5147.6 5176.4 5186.7 4739.8 5258.2 5264.9 5271.1 5316.3 5111.8 5233.8 5236.6 5242.8 5326.8 5126.6 5143.8 5187.0 5212.3 5222.1 5100.3 5112.6 5203.8 5214.1 5243.3 5643.9 5675.3 5680.0 5702.6 5713.7 6031.9 6195.4 6213.3 6217.2 6239.0 6103.8 6114.1 6241.4 6251.7 6283.4 5761.2 5776.4 5778.6 5785.1 5802.0 6232.6 6390.6 6391.5 6393.4 6395.7 5947.6 6050.9 6124.0 6127.1 6144.3 6217.8 6246.2 6269.2 6270.3 6276.5 5122.2 5866.7 6221.3 6232.2 6255.3 6022.7 6073.4 6086.5 6120.6 6147.6 4868.2 4876.4 4881.4 4891.5 4892.8 3670.8 4190.6 4411.9 4414.6 4445.7 5536.1 5584.4 5608.9 5656.7 5659.4 6063.6 6081.0 6084.8 6089.8 6094.5 5592.3 5595.2 5603.6 5632.2 5700.5 5014.8 5317.8 5341.2 5357.5 5437.7 6278.2 6342.6 6346.3 6351.5 6361.9 6232.8 6274.9 6277.2 6304.6 6305.0 6247.5 6289.5 6293.7 6323.1 6328.0 6097.9 6104.3 6137.1 6160.3 6161.8 4750.6 6213.7 6244.3 6250.0 6252.6 5855.4 5895.7 5913.3 5995.6 6075.6 5722.3 5758.5 5778.3 5829.2 5830.7 5646.9 5656.5 5674.6 5680.5 5688.2 5750.1 5786.3 5791.3 5793.8 5797.2 5734.3 5798.0 5811.9 5837.2 5859.8 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:68954.43 Median:39972.00 stddev:150756.07 (377.2%) stddev-sans-first:151091.31 09:12:22 INFO - PROCESS | 1653 | Values: 477.5 506.7 517.6 525.1 551.0 30500.4 38788.2 40503.8 40616.4 42556.6 41519.4 41901.4 41958.8 41998.2 42013.4 9442.2 9615.5 9820.7 9821.7 9833.5 8773.5 9761.2 9765.7 9770.4 9776.1 9707.0 9771.6 9789.4 9792.7 9801.5 9716.3 9754.4 9771.2 9777.3 9790.0 9603.9 9685.2 9711.5 9714.4 9718.9 39525.0 39827.8 39962.0 39982.0 39989.6 3321.5 3627.8 3890.5 3901.3 4067.2 25436.9 29846.4 31225.8 31246.5 31510.9 2815.9 2993.2 3263.5 3328.7 3331.3 481530.0 483113.0 484494.0 485985.0 494472.5 52577.5 52857.5 52936.0 52958.7 52987.7 58594.0 58656.0 58751.7 58798.0 58884.0 15436.0 15622.5 15636.9 15645.2 15652.0 12164.1 12202.4 12210.1 12233.4 12241.9 12787.7 15082.4 15105.6 15120.0 15307.5 5130.8 5175.9 5901.6 6114.5 6139.1 868.0 975.6 984.4 991.7 1057.5 25953.7 26478.6 29415.6 30074.7 30079.4 1037.7 1042.2 1067.5 1096.8 1136.0 14853.4 15091.2 15198.5 15491.9 15532.4 571625.0 627291.0 631526.0 633846.0 634044.0 55719.4 55861.2 55869.0 55941.7 56059.0 1305.8 1344.7 1364.8 1367.2 1368.1 22151.0 28634.3 29243.0 29911.1 30234.7 47346.0 47723.0 47917.6 47917.8 48000.0 576970.0 577157.0 578095.0 578102.1 580498.0 38548.6 38827.2 39292.0 39443.4 39502.0 37949.1 42096.2 46128.0 46660.0 47340.0 37807.0 37843.1 38364.6 39203.2 39410.6 34449.8 37594.4 38551.0 38589.0 38923.0 36791.0 37116.0 37424.6 37896.8 38025.6 29655.5 30439.8 32933.3 33271.8 34194.5 24777.5 24926.0 25245.6 25312.2 25384.2 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33108.46 Median:14290.61 stddev:96293.58 (673.8%) stddev-sans-first:96532.30 09:12:22 INFO - PROCESS | 1653 | Values: 353.4 367.7 375.1 379.4 403.2 15354.5 15524.7 17040.8 20694.3 21346.1 12274.1 14739.1 19758.7 20621.6 20818.3 5266.0 5513.9 5541.5 5554.6 5563.9 4197.9 5483.1 6122.1 6211.0 6267.1 5990.7 6236.1 6238.9 6243.9 6244.2 6139.8 6171.6 6174.1 6203.7 6209.8 5732.1 5879.0 5965.2 5990.3 6014.5 13422.4 13570.5 14307.9 25413.1 25708.0 2121.1 2188.2 2251.4 2541.3 2873.2 9519.4 10181.2 10423.4 10529.5 10629.3 1818.4 1821.3 1889.6 1909.3 1980.7 80030.8 120564.0 122458.0 124069.0 125187.0 11314.7 11390.4 11535.5 11554.4 11737.3 14623.6 14645.6 14651.3 14660.4 14672.6 6934.0 8177.1 8278.9 8414.7 8435.5 7682.7 7718.3 8069.9 8086.9 8309.0 4138.0 4205.6 4423.0 4468.0 4537.9 1855.4 1907.8 1914.4 1916.6 1917.2 839.9 873.4 875.1 904.5 906.3 20288.9 21019.8 21101.4 21111.0 21356.4 930.8 955.2 975.3 1005.6 1012.5 10063.3 10861.2 10887.3 10896.2 10980.5 567867.9 571258.0 574126.0 587929.0 594152.0 77003.6 77436.8 114738.7 121355.8 128525.4 920.0 960.5 970.4 981.8 983.1 15730.4 17653.6 18234.8 18527.4 18966.1 47738.0 48553.5 50535.0 50882.7 52118.7 60333.7 74674.6 76295.2 76487.6 77156.0 7705.8 7897.9 8159.5 8164.8 8406.0 12066.9 12424.1 12547.4 12746.6 13037.8 10734.5 10961.8 11006.8 11064.8 11120.8 10268.6 10699.5 10731.9 10949.9 11162.4 11012.8 11138.2 11207.9 11441.7 11744.6 7881.7 8014.2 8148.9 8269.0 8445.0 8264.6 10768.4 11148.9 11176.2 11269.9 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5656.59 Median:2502.37 stddev:3230.11 (129.1%) stddev-sans-first:3222.91 09:12:22 INFO - PROCESS | 1653 | Values: 1344.1 1404.0 1488.8 1690.8 1899.6 7835.4 8340.5 8347.0 8347.2 8350.2 8532.8 8597.7 8800.9 8911.3 8935.3 2362.5 2388.2 2391.4 2393.6 2398.3 2338.0 2364.1 2380.9 2385.2 2385.3 2082.8 2343.1 2357.8 2371.3 2377.6 2314.1 2332.8 2340.1 2344.5 2361.8 2340.8 2341.1 2341.6 2342.5 2344.1 7422.8 8958.5 8963.6 8973.8 8983.3 4141.7 4809.8 5241.8 5373.7 5374.5 3126.6 3292.7 3383.4 3390.0 3399.2 1019.4 1074.2 1082.6 1097.1 1100.9 11713.7 11759.4 11855.3 11864.9 11879.0 4184.2 4506.3 4659.3 4800.4 4825.9 9304.7 10094.1 10098.6 10104.9 10110.9 4868.1 5259.3 5403.9 5411.6 5464.9 4419.1 4587.4 4597.5 4757.0 4950.9 3784.6 3802.2 3944.0 3946.8 3950.8 1252.9 1272.1 1277.4 1287.1 1287.4 771.0 863.7 870.2 910.0 952.6 5638.3 5723.7 5799.1 5933.4 6090.2 434.6 439.3 440.7 451.6 452.3 3885.7 3911.9 3912.8 3920.5 4029.2 10275.9 10354.5 10620.7 10761.6 10935.2 10607.5 10830.4 10834.3 10835.1 10849.7 8004.9 8229.3 8322.3 8342.4 8356.8 5050.9 5202.9 5952.6 6033.2 6143.7 9069.0 10002.9 10008.8 10197.7 10240.3 9598.4 10061.6 10075.9 10424.2 10449.8 6932.9 7167.8 7538.9 7662.8 7675.6 7891.7 8089.3 8268.2 8424.5 8503.5 7377.5 7505.4 7556.2 7639.0 7645.9 7343.5 7395.3 7400.9 7405.0 7483.1 6178.4 6931.1 7021.3 7028.3 7033.4 4976.7 5036.6 5079.6 5132.1 5147.6 4552.4 4745.0 4896.3 5139.7 5231.9 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4442.79 Median:5000.94 stddev:1380.42 (27.6%) stddev-sans-first:1348.44 09:12:22 INFO - PROCESS | 1653 | Values: 278.6 303.3 309.9 317.2 318.8 4800.9 5258.8 5270.9 5350.9 5356.0 4217.0 4568.6 4593.5 4708.5 4758.3 3459.3 3972.2 3976.9 4011.5 4059.6 3538.7 3552.4 4020.8 4035.3 4044.5 3788.0 3964.6 3996.4 4004.6 4037.6 3517.8 3758.6 3983.2 3997.7 4015.3 3264.7 3441.7 3453.6 3478.9 3483.8 4477.6 5130.0 5198.6 5219.6 5232.9 4387.3 4511.3 4511.9 4518.7 4582.8 5453.3 5469.9 5478.8 5534.8 5550.4 3745.0 3803.8 3815.0 3819.5 3825.9 4969.6 5487.6 5701.3 5781.4 5808.4 3302.5 3777.8 3788.0 3796.8 3818.9 3220.5 3358.8 3834.7 3896.8 3912.7 3602.7 4002.0 4295.7 4511.6 4543.6 4912.9 5503.6 5531.6 5544.4 5546.3 1266.8 1298.5 1319.3 1322.8 1339.4 824.4 849.4 850.0 851.4 853.4 4353.5 4837.3 5001.3 5213.0 5217.6 5083.9 5322.5 5582.7 5583.7 5593.5 4850.5 4954.8 5125.2 5146.0 5189.4 2358.5 2585.6 2751.6 2953.0 2966.0 4887.1 5000.5 5509.8 5641.5 5861.6 5445.4 5680.9 5769.6 5795.7 5839.6 5370.4 5871.0 5895.5 5931.7 5973.9 5210.8 5568.3 5587.8 5631.5 5728.9 4963.8 5651.1 5684.1 5709.0 5729.5 5051.2 5538.6 5987.8 6026.2 6034.2 3559.3 3775.9 3805.6 3823.8 3826.7 4636.2 5508.1 5630.1 5631.7 5639.4 4654.2 5376.8 5529.3 5551.0 5583.5 5256.6 5399.9 5443.7 5463.5 5474.8 4209.5 4362.3 5252.6 5347.5 5373.0 5328.8 5341.8 5372.5 5521.9 5619.9 4507.5 5412.8 5468.6 5473.6 5531.1 09:12:22 INFO - PROCESS | 1653 | 09:12:22 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24466.85 Median:3643.59 stddev:38675.52 (1061.5%) stddev-sans-first:38742.87 09:12:22 INFO - PROCESS | 1653 | Values: 706.8 756.5 758.2 764.3 799.2 15514.7 15753.2 15766.6 15767.0 15769.9 14171.9 14285.4 14291.8 14292.4 14294.9 686.4 704.2 710.2 829.1 841.5 862.9 863.5 873.1 875.5 877.5 927.7 928.3 930.8 932.0 933.4 719.5 743.4 745.4 923.4 923.5 549.4 549.9 666.8 682.9 691.3 14955.0 14991.3 14993.4 15001.3 15030.1 13636.7 13811.9 13978.4 14034.7 14149.3 11172.5 11722.5 12154.7 12396.9 12408.4 4558.9 4563.1 4623.8 4768.2 4772.2 91050.5 112078.9 113638.0 116821.0 117311.8 10950.0 10985.7 11009.1 11119.0 11234.9 21766.2 21803.9 21809.7 21869.8 21880.8 7847.5 7961.5 8094.8 8103.4 8162.6 3788.8 4261.7 4453.0 4526.4 4543.2 3353.4 3496.4 3496.4 3497.1 3498.4 629.9 635.0 644.3 657.3 658.9 582.9 605.1 624.2 640.1 643.3 8968.2 9044.0 9136.5 9210.5 9335.4 568.5 585.9 613.8 629.0 643.9 4406.5 4452.9 4614.9 4756.5 4879.7 90493.0 125945.0 147526.0 148186.3 155792.0 136285.0 138027.3 138954.6 139652.0 143564.3 76925.0 85533.6 101836.0 115530.0 120644.4 9775.3 9780.3 9870.9 9874.0 9887.3 58243.4 59773.0 66498.7 67867.7 69607.6 81645.6 83025.2 84415.6 85720.0 94642.0 13029.6 13132.6 13206.4 13586.4 13742.5 14871.7 14879.2 14953.4 14987.4 15519.1 13380.9 13774.3 13880.2 14307.0 14442.7 13756.7 13989.8 13990.9 14270.0 14544.8 12525.3 12651.9 13776.7 13925.2 14116.6 11342.0 11582.4 11607.9 11648.3 11918.9 10687.0 11317.0 11715.6 11728.4 11754.4 09:12:22 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:12:22 INFO - PROCESS | 1653 | 09:12:23 INFO - TEST-INFO | 1653: exit 0 09:12:23 INFO - TEST-OK | dromaeo_css | took 1119614ms 09:12:23 INFO - TEST-START | kraken 09:12:23 INFO - operating with platform_type : mac_ 09:12:23 INFO - Initialising browser for kraken test... 09:12:23 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpCrxKzd/profile http://localhost:49283/getInfo.html) 09:12:25 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:12:25 INFO - PROCESS | 1663 | colorDepth:24 09:12:25 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:12:25 INFO - PROCESS | 1663 | __metrics 09:12:25 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:25 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:26 INFO - TEST-INFO | 1663: exit 0 09:12:26 INFO - Browser initialized. 09:12:26 INFO - Running cycle 1/1 for kraken test... 09:12:26 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpCrxKzd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:38 INFO - PROCESS | 1664 | RSS: Main: 235769856 09:12:38 INFO - PROCESS | 1664 | 09:12:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:42 INFO - PROCESS | 1664 | RSS: Main: 294604800 09:12:42 INFO - PROCESS | 1664 | 09:12:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 09:12:45 INFO - PROCESS | 1664 | RSS: Main: 293982208 09:12:45 INFO - PROCESS | 1664 | 09:12:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 09:12:49 INFO - PROCESS | 1664 | RSS: Main: 383537152 09:12:49 INFO - PROCESS | 1664 | 09:12:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 09:12:52 INFO - PROCESS | 1664 | RSS: Main: 285016064 09:12:52 INFO - PROCESS | 1664 | 09:12:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:54 INFO - PROCESS | 1664 | RSS: Main: 289058816 09:12:54 INFO - PROCESS | 1664 | 09:12:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:58 INFO - PROCESS | 1664 | RSS: Main: 327467008 09:12:58 INFO - PROCESS | 1664 | 09:13:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:01 INFO - PROCESS | 1664 | RSS: Main: 328638464 09:13:01 INFO - PROCESS | 1664 | 09:13:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 09:13:04 INFO - PROCESS | 1664 | RSS: Main: 328032256 09:13:04 INFO - PROCESS | 1664 | 09:13:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:07 INFO - PROCESS | 1664 | RSS: Main: 301740032 09:13:07 INFO - PROCESS | 1664 | 09:13:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:09 INFO - PROCESS | 1664 | RSS: Main: 307712000 09:13:09 INFO - PROCESS | 1664 | 09:13:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:12 INFO - PROCESS | 1664 | RSS: Main: 314933248 09:13:12 INFO - PROCESS | 1664 | 09:13:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:16 INFO - PROCESS | 1664 | RSS: Main: 318349312 09:13:16 INFO - PROCESS | 1664 | 09:13:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:19 INFO - PROCESS | 1664 | RSS: Main: 319045632 09:13:19 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 09:13:21 INFO - PROCESS | 1664 | RSS: Main: 363401216 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | __start_tp_report 09:13:21 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:13:21 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:13:21 INFO - PROCESS | 1664 | |i|pagename|runs| 09:13:21 INFO - PROCESS | 1664 | |0;ai-astar;112;113;114;113;113;114;113;114;112;114 09:13:21 INFO - PROCESS | 1664 | |1;audio-beat-detection;151;136;139;159;111;113;112;112;146;114 09:13:21 INFO - PROCESS | 1664 | |2;audio-dft;181;183;181;181;182;181;179;182;178;181 09:13:21 INFO - PROCESS | 1664 | |3;audio-fft;80;59;59;88;62;58;59;59;82;57 09:13:21 INFO - PROCESS | 1664 | |4;audio-oscillator;90;72;120;99;53;66;114;97;93;91 09:13:21 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;87;85;87;85;87;83;88;85;87;83 09:13:21 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;96;96;97;94;95;96;96;95;95 09:13:21 INFO - PROCESS | 1664 | |7;imaging-desaturate;86;88;86;84;84;83;85;85;84;85 09:13:21 INFO - PROCESS | 1664 | |8;json-parse-financial;57;50;54;53;54;53;55;55;54;54 09:13:21 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;64;67;71;74;76;74;67;69;68;69 09:13:21 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;91;87;91;88;90;89;92;92;111;90 09:13:21 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;138;152;145;144;144;155;161;151;189;139 09:13:21 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;175;173;172;183;172;173;172;170;183;214 09:13:21 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;68;70;70;69;68;69;69;80;87;88 09:13:21 INFO - PROCESS | 1664 | __end_tp_report 09:13:21 INFO - PROCESS | 1664 | __start_cc_report 09:13:21 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1899 09:13:21 INFO - PROCESS | 1664 | __end_cc_report 09:13:21 INFO - PROCESS | 1664 | __startTimestamp1475684001783__endTimestamp 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:13:21 INFO - PROCESS | 1664 | Number of tests: 14 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:0.79 (0.7%) stddev-sans-first:0.71 09:13:21 INFO - PROCESS | 1664 | Values: 112.0 113.0 114.0 113.0 113.0 114.0 113.0 114.0 112.0 114.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:137.50 stddev:18.87 (13.7%) stddev-sans-first:18.31 09:13:21 INFO - PROCESS | 1664 | Values: 151.0 136.0 139.0 159.0 111.0 113.0 112.0 112.0 146.0 114.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:180.90 Median:181.00 stddev:1.45 (0.8%) stddev-sans-first:1.54 09:13:21 INFO - PROCESS | 1664 | Values: 181.0 183.0 181.0 181.0 182.0 181.0 179.0 182.0 178.0 181.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.50 stddev:11.98 (19.8%) stddev-sans-first:11.64 09:13:21 INFO - PROCESS | 1664 | Values: 80.0 59.0 59.0 88.0 62.0 58.0 59.0 59.0 82.0 57.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:90.50 stddev:20.77 (23.0%) stddev-sans-first:22.03 09:13:21 INFO - PROCESS | 1664 | Values: 90.0 72.0 120.0 99.0 53.0 66.0 114.0 97.0 93.0 91.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.77 (2.0%) stddev-sans-first:1.81 09:13:21 INFO - PROCESS | 1664 | Values: 87.0 85.0 87.0 85.0 87.0 83.0 88.0 85.0 87.0 83.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:96.00 stddev:0.97 (1.0%) stddev-sans-first:0.88 09:13:21 INFO - PROCESS | 1664 | Values: 94.0 96.0 96.0 97.0 94.0 95.0 96.0 96.0 95.0 95.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.41 (1.7%) stddev-sans-first:1.45 09:13:21 INFO - PROCESS | 1664 | Values: 86.0 88.0 86.0 84.0 84.0 83.0 85.0 85.0 84.0 85.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.79 (3.3%) stddev-sans-first:1.51 09:13:21 INFO - PROCESS | 1664 | Values: 57.0 50.0 54.0 53.0 54.0 53.0 55.0 55.0 54.0 54.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:3.78 (5.4%) stddev-sans-first:3.36 09:13:21 INFO - PROCESS | 1664 | Values: 64.0 67.0 71.0 74.0 76.0 74.0 67.0 69.0 68.0 69.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:90.50 stddev:6.84 (7.6%) stddev-sans-first:7.24 09:13:21 INFO - PROCESS | 1664 | Values: 91.0 87.0 91.0 88.0 90.0 89.0 92.0 92.0 111.0 90.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:151.50 stddev:14.91 (9.8%) stddev-sans-first:14.96 09:13:21 INFO - PROCESS | 1664 | Values: 138.0 152.0 145.0 144.0 144.0 155.0 161.0 151.0 189.0 139.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:174.00 stddev:13.22 (7.6%) stddev-sans-first:13.95 09:13:21 INFO - PROCESS | 1664 | Values: 175.0 173.0 172.0 183.0 172.0 173.0 172.0 170.0 183.0 214.0 09:13:21 INFO - PROCESS | 1664 | 09:13:21 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:70.00 stddev:8.02 (11.5%) stddev-sans-first:8.23 09:13:21 INFO - PROCESS | 1664 | Values: 68.0 70.0 70.0 69.0 68.0 69.0 69.0 80.0 87.0 88.0 09:13:21 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:13:21 INFO - PROCESS | 1664 | 09:13:22 INFO - TEST-INFO | 1664: exit 0 09:13:22 INFO - TEST-OK | kraken | took 59321ms 09:13:22 INFO - SUITE-END | took 1178s 09:13:22 INFO - Completed test suite (00:19:39) 09:13:22 INFO - javascript benchmark 09:13:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.819389933554, 3304.90085297441, 3323.451630480971, 3341.832908354582, 3379.341028499442, 5547.72261386931, 5607.887842243158, 5621.690807005613, 5647.830565083043, 5667.8583035424135, 4616.515265897075, 5612.186232996223, 5724.685142317171, 5727.971360143204, 5740.253767010283, 4828.517148285178, 5142.408623008346, 5147.562457191125, 5176.378834539861, 5186.662866913657, 4739.763011849411, 5258.211268309765, 5264.92102618461, 5271.103912334912, 5316.308879845615, 5111.821086261963, 5233.842984710464, 5236.581073514109, 5242.842714718546, 5326.813561525348, 5126.641135120506, 5143.819966301162, 5187.04039752647, 5212.270282160501, 5222.0861349264105, 5100.311457953166, 5112.565431938289, 5203.791848326063, 5214.139666954986, 5243.318368612094, 5643.8589035274035, 5675.318961724578, 5680.034394153004, 5702.629329093637, 5713.742881570298, 6031.939680603182, 6195.380461953769, 6213.285472170713, 6217.191765070557, 6239.0329498927285, 6103.822585931238, 6114.114890844268, 6241.438270555672, 6251.687415629201, 6283.403076707751, 5761.193432919395, 5776.393478684678, 5778.624389414675, 5785.074388097884, 5802.013657678205, 6232.594881332699, 6390.616563006234, 6391.488680905516, 6393.392627700361, 6395.744170233232, 5947.553933454921, 6050.939490605126, 6124.0, 6127.142200091991, 6144.310314663503, 6217.751289948442, 6246.219222597176, 6269.247690277196, 6270.31026587075, 6276.529260305404, 5122.231665250168, 5866.73599688015, 6221.3156552779155, 6232.158658581124, 6255.311915689362, 6022.668753218615, 6073.392660733891, 6086.513078953673, 6120.632762034204, 6147.586055207327, 4868.172410690191, 4876.366072410564, 4881.389826271716, 4891.510848915151, 4892.804287828447, 3670.7614005089586, 4190.580941905846, 4411.933820992687, 4414.646828253796, 4445.73325600465, 5536.141898005735, 5584.357798853102, 5608.887822243532, 5656.660600363992, 5659.377468478464, 6063.575549711478, 6081.0, 6084.787032453843, 6089.756409743561, 6094.451499365078, 5592.3009623797025, 5595.244641973281, 5603.607747457639, 5632.211490391349, 5700.458456446631, 5014.774335154927, 5317.813876514303, 5341.225522299244, 5357.464253574689, 5437.673739575559, 6278.152449419453, 6342.587731797326, 6346.301906790157, 6351.523635727432, 6361.936380636227, 6232.75068997235, 6274.937250627435, 6277.18396608438, 6304.590201636879, 6305.0, 6247.468965137832, 6289.49684025286, 6293.748250070129, 6323.08315294289, 6327.96836015817, 6097.887666312884, 6104.270499843183, 6137.079438084323, 6160.3223645398075, 6161.815145545641, 4750.643701722453, 6213.720382582705, 6244.250689917239, 6249.96875015622, 6252.562320637512, 5855.38518455556, 5895.67573783446, 5913.2608423947, 5995.5803093783015, 6075.635461872125, 5722.284714410699, 5758.539316854558, 5778.335491418541, 5829.213056237523, 5830.679312637845, 5646.915296270641, 5656.462636049569, 5674.57440691958, 5680.460356266148, 5688.232088668143, 5750.079987202028, 5786.276715410574, 5791.333996590446, 5793.768249269982, 5797.246357973437, 5734.311882574118, 5798.043322851851, 5811.94188058114, 5837.153612726216, 5859.794907178229], "value": 5610.919646508873, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.45570050142845, 506.7213032831943, 517.567830861231, 525.1282870435078, 551.0356875467932, 30500.38999220017, 38788.22423551531, 40503.797481012625, 40616.39075413871, 42556.57443425565, 41519.37720934188, 41901.371479427755, 41958.79020604893, 41998.16003679924, 42013.36979945303, 9442.197413219885, 9615.519224038804, 9820.705378838646, 9821.705348839547, 9833.508324583741, 8773.517456539888, 9761.170300524429, 9765.707028789147, 9770.364926279804, 9776.120149186572, 9707.0, 9771.560279787427, 9789.412635241873, 9792.706218813448, 9801.509924503782, 9716.31985760997, 9754.365966212245, 9771.169450596708, 9777.266704997153, 9790.0, 9603.90396096044, 9685.225181985425, 9711.465869377182, 9714.368566043255, 9718.854217186748, 39525.0, 39827.80086099551, 39962.0, 39982.0, 39989.60010399888, 3321.485169798685, 3627.782333060025, 3890.513685789276, 3901.317269477858, 4067.1662309226535, 25436.87281563599, 29846.403071938657, 31225.8438707805, 31246.53130203049, 31510.89711860074, 2815.8873645054173, 2993.191838203673, 3263.543103965447, 3328.6671332866763, 3331.3337332533347, 481530.0, 483113.0, 484494.0, 485985.0, 494472.5276373631, 52577.474225257254, 52857.47142528526, 52936.0, 52958.73520632449, 52987.73506132445, 58594.0, 58656.0, 58751.70624146937, 58798.0, 58884.0, 15436.0, 15622.531324060297, 15636.905416620955, 15645.217739112999, 15652.0, 12164.148509604243, 12202.389880506116, 12210.145289829627, 12233.388330583613, 12241.877581224073, 12787.744245115231, 15082.416346283702, 15105.622359441102, 15120.0, 15307.540773776804, 5130.7947682093045, 5175.922361164586, 5901.557383196277, 6114.510839132859, 6139.079138129317, 868.0, 975.6341371985505, 984.4487087231173, 991.6727479931606, 1057.5353135906823, 25953.74046259513, 26478.6028209577, 29415.558766618517, 30074.69925300719, 30079.398412031638, 1037.739146936476, 1042.2287507244585, 1067.5249513966367, 1096.780643871229, 1135.9943200283947, 14853.405863765545, 15091.169985650895, 15198.468053618291, 15491.922540387452, 15532.378704851679, 571625.0, 627291.0, 631526.0, 633846.0, 634044.0, 55719.44280557224, 55861.16208256879, 55869.0, 55941.720291398284, 56059.0, 1305.7976013717698, 1344.6503908983734, 1364.7952807078818, 1367.2070199284392, 1368.0560413314781, 22151.0, 28634.284142896595, 29242.976495822542, 29911.102666920026, 30234.697653023188, 47346.0, 47723.04553908833, 47917.57277621974, 47917.76041119772, 48000.03999920052, 576970.0, 577157.0, 578095.0, 578102.1094894499, 580498.0, 38548.6145138545, 38827.2234555313, 39292.0, 39443.40834887536, 39502.0, 37949.051273718374, 42096.15807683768, 46128.0, 46660.0, 47340.0, 37807.0, 37843.05392365213, 38364.61635383722, 39203.2159356817, 39410.605893940694, 34449.827750861084, 37594.436083458226, 38551.0, 38589.0, 38923.0, 36791.0, 37116.0, 37424.62575374211, 37896.810515948346, 38025.619743802206, 29655.548168771795, 30439.847800760854, 32933.34133317368, 33271.83364083164, 34194.48708269328, 24777.513349199107, 24926.0029598814, 25245.621315680648, 25312.202441784117, 25384.238472845846], "value": 19547.530397292823, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4345047923321, 367.7039982813835, 375.12595652130557, 379.3948651900219, 403.22983102274645, 15354.539363819089, 15524.689506209868, 17040.829591704096, 20694.275700350492, 21346.146154153816, 12274.079444041688, 14739.115653060824, 19758.703619445685, 20621.587568248626, 20818.271360502386, 5265.973670131644, 5513.9448605514035, 5541.5289700375315, 5554.638948468357, 5563.8887222255535, 4197.8530751423705, 5483.122700367942, 6122.112293717409, 6211.0, 6267.091271765591, 5990.73041713124, 6236.126942228092, 6238.9064164037345, 6243.875122497548, 6244.188255526775, 6139.846503837418, 6171.598846075036, 6174.10475481055, 6203.720832562545, 6209.813705588839, 5732.111522713986, 5878.970605146989, 5965.194698715662, 5990.341062483123, 6014.458698717136, 13422.395992180376, 13570.525031623845, 14307.92690548213, 25413.11054113116, 25708.0, 2121.0985331234165, 2188.2122435923056, 2251.448395143184, 2541.301142185895, 2873.227335621115, 9519.381240219433, 10181.214829030343, 10423.374597524173, 10529.473526323729, 10629.309094908805, 1818.399928023749, 1821.3443160462223, 1889.5937373464772, 1909.312647446918, 1980.7326010988477, 80030.79953800698, 120564.0, 122458.0, 124069.0, 125187.0, 11314.717132071599, 11390.430478476042, 11535.538578456935, 11554.364509952033, 11737.295762254293, 14623.561293161221, 14645.560633181021, 14651.267436628126, 14660.413583456542, 14672.633184170267, 6934.0, 8177.059638141571, 8278.875816862752, 8414.747557573282, 8435.536045517432, 7682.692692292358, 7718.3053525183, 8069.888016324199, 8086.919130808734, 8308.958455207685, 4137.9861933825805, 4205.600467955539, 4423.049044455393, 4467.977660111678, 4537.863864084083, 1855.396995976313, 1907.8378337841161, 1914.4448110048208, 1916.559191385989, 1917.166032775719, 839.8530257204965, 873.4279047224078, 875.072423231364, 904.4708845325426, 906.3383729877227, 20288.85522743977, 21019.789802101783, 21101.366958991253, 21111.0, 21356.35930922075, 930.7905721212672, 955.1584037488554, 975.2734213011352, 1005.6429967361628, 1012.549415510091, 10063.345882517759, 10861.1854110942, 10887.332603512032, 10896.202126649172, 10980.505877235544, 567867.8918897236, 571258.0, 574126.0, 587929.0, 594152.0292398597, 77003.61498192586, 77436.83844742333, 114738.70522589669, 121355.78644213622, 128525.3573732144, 920.0, 960.490939801906, 970.3838062830013, 981.7938232971156, 983.0955520920627, 15730.449434269745, 17653.646927061644, 18234.817651823312, 18527.444176674722, 18966.146523406755, 47738.0, 48553.514464856315, 50535.0, 50882.745586271834, 52118.739406302724, 60333.69833150982, 74674.62662686652, 76295.23704762882, 76487.55805988633, 77156.0, 7705.845883082195, 7897.881531776913, 8159.510429374256, 8164.7958801030445, 8405.991281046314, 12066.879331206575, 12424.130310878152, 12547.372631368578, 12746.553870614483, 13037.804432933703, 10734.463276836283, 10961.780764384508, 11006.834897476385, 11064.834027489433, 11120.777584448428, 10268.64059757905, 10699.465026748787, 10731.946340268249, 10949.945250273699, 11162.44187790591, 11012.834807477733, 11138.220324577525, 11207.887921120684, 11441.656750297505, 11744.647660570197, 7881.724139655084, 8014.238647328494, 8148.918510815053, 8269.04905935825, 8445.028821685584, 8264.586770661563, 10768.3538987661, 11148.88851111511, 11176.161787866105, 11269.856330305742], "value": 9259.001508456973, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.0793056756117, 1403.964900877478, 1488.8064551608288, 1690.780198574185, 1899.591587808621, 7835.412344074196, 8340.45787023843, 8346.958265208674, 8347.20701533354, 8350.248477637011, 8532.829343413143, 8597.699080532167, 8800.91199088009, 8911.331650126234, 8935.285177185826, 2362.492064206195, 2388.2118900762794, 2391.354334329739, 2393.6050551658955, 2398.2805158452393, 2337.9532409351805, 2364.137089962169, 2380.9285721428387, 2385.2486466763003, 2385.2844146755906, 2082.7708952015264, 2343.097907305691, 2357.7524359942217, 2371.288613415977, 2377.5839228135005, 2314.1090680088287, 2332.825038122139, 2340.1458467659354, 2344.5076533927895, 2361.8346715729904, 2340.8010319122895, 2341.1454818990946, 2341.555104530134, 2342.4846533762543, 2344.050659482912, 7422.7773166804545, 8958.46249225042, 8963.641454341818, 8973.775655608597, 8983.281337492985, 4141.730787498803, 4809.807607695688, 5241.79032838686, 5373.731313434352, 5374.543163831079, 3126.609173853268, 3292.703656670911, 3383.4079036168573, 3389.9830500847584, 3399.2351720862857, 1019.3985548526331, 1074.1943542343242, 1082.6373164989739, 1097.1058587251364, 1100.8899110089008, 11713.70715732105, 11759.35323557204, 11855.288682679067, 11864.940675296655, 11878.999999999913, 4184.2468355695655, 4506.301523263899, 4659.301104834302, 4800.447948485899, 4825.927611085836, 9304.72085837426, 10094.091531762177, 10098.596056157818, 10104.949475252577, 10110.898891010997, 4868.099401610713, 5259.342582177228, 5403.864903377447, 5411.621186516984, 5464.890702185934, 4419.1382680377665, 4587.357769912215, 4597.494275629678, 4756.952430475721, 4950.900981980412, 3784.564775050849, 3802.1825307558456, 3943.9802800985813, 3946.8421263149726, 3950.7827069511454, 1252.8997680185564, 1272.10952333367, 1277.444311724403, 1287.0990306785288, 1287.3691890973355, 770.9922900770921, 863.6977058029639, 870.2124577257592, 909.9808214799335, 952.5854106651617, 5638.323401191801, 5723.656580605094, 5799.101139323411, 5933.436323549256, 6090.177825993523, 434.6023388599459, 439.29054576858147, 440.6562880952862, 451.58228638509246, 452.3079688077247, 3885.7279990400402, 3911.882643520699, 3912.7847968361443, 3920.4903362562686, 4029.1538776857187, 10275.897241027644, 10354.482275886176, 10620.734481637866, 10761.569537218424, 10935.179861510418, 10607.469626518643, 10830.404327761891, 10834.349939003685, 10835.133189344833, 10849.67450976472, 8004.919950800534, 8229.25936665691, 8322.250997410261, 8342.374321925758, 8356.832863342699, 5050.949490505048, 5202.947970520247, 5952.553558483044, 6033.155358249761, 6143.723532440961, 9069.047749986421, 10002.949985250027, 10008.799824003334, 10197.694069177936, 10240.334378265388, 9598.42409455435, 10061.625208951931, 10075.899241007495, 10424.218183636409, 10449.791004180026, 6932.896006560007, 7167.820804479929, 7538.924610753822, 7662.8084297893, 7675.577843218676, 7891.723789667334, 8089.312408445113, 8268.214519620626, 8424.536650484037, 8503.489790612335, 7377.483576149618, 7505.437092217996, 7556.168821429528, 7639.0, 7645.923540764744, 7343.48595598303, 7395.334419902019, 7400.851982960203, 7405.0, 7483.102027756704, 6178.41305076017, 6931.133608298963, 7021.262767409348, 7028.262032486516, 7033.437325014089, 4976.676516026447, 5036.597072234285, 5079.593632509464, 5132.076226279156, 5147.639665223548, 4552.430946131733, 4745.0, 4896.2655601660035, 5139.717315547681, 5231.947680523298], "value": 4454.786777970989, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.6447279718359, 303.258532887091, 309.92716711572785, 317.2006543510354, 318.8198667752718, 4800.879978000552, 5258.7633556489955, 5270.894582108355, 5350.946490535093, 5356.0, 4216.957830421695, 4568.565986231311, 4593.540645935404, 4708.458527269365, 4758.2862570614425, 3459.290845376692, 3972.1857019310974, 3976.912121136412, 4011.538673052607, 4059.5994381938185, 3538.7345949053797, 3552.4493703476, 4020.758754474726, 4035.253478106545, 4044.4539987101657, 3788.0, 3964.6035396460265, 3996.42051902474, 4004.6172527346184, 4037.596240375968, 3517.7889326640484, 3758.624137586247, 3983.203359328146, 3997.6801855851754, 4015.297322968469, 3264.7061764441314, 3441.741869359783, 3453.620101788801, 3478.930421391558, 3483.756137070406, 4477.574630410106, 5130.0, 5198.558122559587, 5219.556337711299, 5232.92150617741, 4387.288957306653, 4511.278195488706, 4511.8646440606835, 4518.683692141552, 4582.816687332536, 5453.291072160633, 5469.890602187974, 5478.780848766084, 5534.833954981357, 5550.417206193332, 3744.962550374516, 3803.790791506466, 3815.0, 3819.5416550014174, 3825.9426108608395, 4969.627277954168, 5487.588430867661, 5701.258836351248, 5781.392953739839, 5808.361080281165, 3302.4716045432615, 3777.8111094445167, 3787.962120378761, 3796.822984874698, 3818.866339678098, 3220.4847224443974, 3358.7648864579733, 3834.6932245420858, 3896.785676787747, 3912.7456715313415, 3602.7117830573493, 4002.0184962203343, 4295.6778241632, 4511.639068874482, 4543.5910768031035, 4912.852614421573, 5503.642263252876, 5531.612787104864, 5544.362398324163, 5546.334439867242, 1266.8345122487333, 1298.4676282724038, 1319.3139567424885, 1322.801579763024, 1339.4173534512518, 824.3570015388007, 849.4096602861076, 849.9872501912477, 851.429542206723, 853.3557164340883, 4353.455818022747, 4837.274408838703, 5001.324821149171, 5212.973935130376, 5217.556507696889, 5083.949160508348, 5322.467753224647, 5582.7424348347795, 5583.720813959286, 5593.468620481048, 4850.5149485051215, 4954.835613630794, 5125.1799712045895, 5145.970805838847, 5189.4032186299055, 2358.4575547624145, 2585.5733803922517, 2751.6010178524, 2952.9557056644167, 2966.0, 4887.120318342732, 5000.549950504472, 5509.807156749494, 5641.548676105984, 5861.633083352845, 5445.373782015119, 5680.914786278209, 5769.596128271065, 5795.739191736382, 5839.620424672383, 5370.355557333067, 5870.970645146747, 5895.528357731286, 5931.703414829328, 5973.940260597338, 5210.843674689765, 5568.331800184003, 5587.832365029056, 5631.465010823965, 5728.914066288926, 4963.801447942042, 5651.067573850433, 5684.147377893184, 5708.971455142698, 5729.512991395778, 5051.221827994515, 5538.615346163459, 5987.820365389045, 6026.216591843032, 6034.185384973148, 3559.2881423714844, 3775.905602359853, 3805.6004119567046, 3823.751456155341, 3826.6556009959236, 4636.168713358137, 5508.11870100798, 5630.0991841305195, 5631.746571404215, 5639.351474580311, 4654.185517534485, 5376.8118115865755, 5529.253550770755, 5550.972245138749, 5583.469570390969, 5256.583220906288, 5399.946000539944, 5443.727813609383, 5463.4536546346635, 5474.795544980098, 4209.536950935456, 4362.302031674917, 5252.606054545848, 5347.4652534747775, 5372.973135134299, 5328.786848526015, 5341.786328546815, 5372.5433338166695, 5521.944780552143, 5619.8876022480135, 4507.526709695435, 5412.810551630674, 5468.617196796186, 5473.562115030868, 5531.064127555484], "value": 4003.2838575383357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.8374273916995, 756.4893696754689, 758.188738050286, 764.3235736373312, 799.2447137455107, 15514.689706205869, 15753.212339383028, 15766.605834854134, 15766.975146615461, 15769.92115039426, 14171.929140354283, 14285.357158927847, 14291.785623215661, 14292.428302867898, 14294.857051429482, 686.423404340354, 704.2007321689894, 710.1797423975316, 829.1294141151782, 841.5329492131864, 862.9482231066156, 863.5336918064238, 873.0876234335135, 875.5359659380539, 877.4954401219293, 927.7309580221728, 928.2666693312275, 930.7859192385722, 932.0260327957301, 933.444600462727, 719.5251134251369, 743.4164181117851, 745.3701622129292, 923.4367036107968, 923.4966943016072, 549.3874330121926, 549.8542886135177, 666.7533012785252, 682.9419499342562, 691.325957191739, 14955.0, 14991.250437478082, 14993.400263989428, 15001.3249403776, 15030.098194108388, 13636.659083522893, 13811.861881381157, 13978.440862365493, 14034.719305613933, 14149.314625469522, 11172.497237624326, 11722.472488738027, 12154.662987071415, 12396.876031239752, 12408.441620127116, 4558.8632341029825, 4563.133004729124, 4623.8150473981, 4768.213244814601, 4772.188727916227, 91050.54474727584, 112078.87921120766, 113638.0, 116820.99999999916, 117311.8268817318, 10950.0, 10985.725356865982, 11009.06422954058, 11119.0, 11234.943825280821, 21766.238181663564, 21803.890980544995, 21809.67285490719, 21869.781302186773, 21880.78119218819, 7847.529148251123, 7961.482503637245, 8094.797630059295, 8103.392245581605, 8162.551059691774, 3788.8105594720155, 4261.70168088237, 4452.977735111369, 4526.434195725535, 4543.182227199136, 3353.3796247694254, 3496.3881320768764, 3496.3881320768764, 3497.090756403353, 3498.370293347171, 629.9338569450233, 634.9873002539923, 644.2816259870211, 657.3163909534031, 658.9029266271604, 582.9300483941954, 605.0533899907479, 624.1854380034099, 640.091070679636, 643.2988043033116, 8968.23769979559, 9043.954780226057, 9136.451812891248, 9210.493422861808, 9335.439873607469, 568.4855206038542, 585.871108356161, 613.8404014956144, 628.9905651415232, 643.8937575300023, 4406.493253275914, 4452.910941781146, 4614.907701845944, 4756.500567440438, 4879.707217566957, 90493.0, 125945.0, 147526.0, 148186.25906870398, 155792.0, 136285.0, 138027.30986345003, 138954.6104538962, 139652.0, 143564.28217859054, 76925.0, 85533.57233213795, 101836.0, 115530.0, 120644.3967780173, 9775.315727898978, 9780.266480013885, 9870.851937220803, 9874.0, 9887.258455616002, 58243.4175658238, 59773.0, 66498.67002660017, 67867.66066169637, 69607.60784784377, 81645.59177204313, 83025.16974830175, 84415.57792211, 85720.0, 94642.0, 13029.609111726664, 13132.606021819362, 13206.405711742802, 13586.38861251266, 13742.519011834538, 14871.659957211661, 14879.181645009196, 14953.40186392532, 14987.400503980154, 15519.068855868683, 13380.933095334462, 13774.311284435538, 13880.236586987812, 14307.0, 14442.714170939968, 13756.724865502434, 13989.790153147507, 13990.860091398956, 14270.001099923322, 14544.781828272373, 12525.311107889154, 12651.92458640989, 13776.724465510833, 13925.23411212393, 14116.576502704935, 11342.0, 11582.420878956187, 11607.90460506748, 11648.301101933912, 11918.940405297919, 10687.0, 11317.0, 11715.648530544097, 11728.354940478015, 11754.353510557], "value": 7823.51195215084, "unit": "score"}], "name": "dromaeo_css", "value": 7220.440795753117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 112.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 136.0, 139.0, 159.0, 111.0, 113.0, 112.0, 112.0, 146.0, 114.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 181.0, 181.0, 182.0, 181.0, 179.0, 182.0, 178.0, 181.0], "value": 180.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 88.0, 62.0, 58.0, 59.0, 59.0, 82.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 120.0, 99.0, 53.0, 66.0, 114.0, 97.0, 93.0, 91.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 83.0, 88.0, 85.0, 87.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 97.0, 94.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 84.0, 84.0, 83.0, 85.0, 85.0, 84.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 50.0, 54.0, 53.0, 54.0, 53.0, 55.0, 55.0, 54.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 71.0, 74.0, 76.0, 74.0, 67.0, 69.0, 68.0, 69.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 88.0, 90.0, 89.0, 92.0, 92.0, 111.0, 90.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 152.0, 145.0, 144.0, 144.0, 155.0, 161.0, 151.0, 189.0, 139.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 172.0, 183.0, 172.0, 173.0, 172.0, 170.0, 183.0, 214.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 69.0, 68.0, 69.0, 69.0, 80.0, 87.0, 88.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1465.5, "alertThreshold": 2.0}]} 09:13:23 INFO - Return code: 0 09:13:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:23 INFO - # TBPL SUCCESS # 09:13:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:23 INFO - 2016-10-05 09:13:23.088 screenresolution[1668:10179] starting screenresolution argv=screenresolution get 09:13:23 INFO - 2016-10-05 09:13:23.091 screenresolution[1668:10179] Display 0: 1600x1200x32@60 09:13:23 INFO - 2016-10-05 09:13:23.097 screenresolution[1669:10182] starting screenresolution argv=screenresolution list 09:13:23 INFO - Available Modes on Display 0 09:13:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:23 INFO - Intel Iris: 09:13:23 INFO - Chipset Model: Intel Iris 09:13:23 INFO - Type: GPU 09:13:23 INFO - Bus: Built-In 09:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:23 INFO - Vendor: Intel (0x8086) 09:13:23 INFO - Device ID: 0x0a2e 09:13:23 INFO - Revision ID: 0x0009 09:13:23 INFO - Displays: 09:13:23 INFO - Display: 09:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:23 INFO - Display Serial Number: 1600x1200 60 09:13:23 INFO - Main Display: Yes 09:13:23 INFO - Mirror: Off 09:13:23 INFO - Online: Yes 09:13:23 INFO - Rotation: Supported 09:13:23 INFO - Return code: 0 09:13:23 INFO - Running post-action listener: _resource_record_post_action 09:13:23 INFO - [mozharness: 2016-10-05 16:13:23.332007Z] Finished run-tests step (success) 09:13:23 INFO - Running post-run listener: _resource_record_post_run 09:13:23 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 37528576; Write bytes: 708353024; Read time: 252; Write time: 1988 09:13:23 INFO - TinderboxPrint: CPU usage
53.4% 09:13:23 INFO - TinderboxPrint: I/O read bytes / time
37,528,576 / 252 09:13:23 INFO - TinderboxPrint: I/O write bytes / time
708,353,024 / 1,988 09:13:23 INFO - TinderboxPrint: CPU idle
2,262.8 (46.6%) 09:13:23 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 09:13:23 INFO - TinderboxPrint: CPU user
1,624.6 (33.4%) 09:13:23 INFO - TinderboxPrint: Swap in / out
445,476,864 / 0 09:13:23 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387656192; Write bytes: 381212672; Read time: 29142; Write time: 775 09:13:23 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36570112; Write bytes: 298607616; Read time: 240; Write time: 1148 09:13:23 INFO - Running post-run listener: _upload_blobber_files 09:13:23 INFO - Blob upload gear active. 09:13:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:23 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 09:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:13:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:13:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:25 INFO - (blobuploader) - INFO - Done attempting. 09:13:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:13:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:13:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:26 INFO - (blobuploader) - INFO - Done attempting. 09:13:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:28 INFO - (blobuploader) - INFO - Done attempting. 09:13:28 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:28 INFO - Return code: 0 09:13:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df46b58c59ef74ee5f55fe8f7d504345f1645a3eb9441989386077bad36e72806c13b849c806d118f349cd93af0a5bcb7d1481239070e25d70477a46cafa58bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165baba936983d62bb306790749f1f5f15d72ae3aabfceab9e64418fcaad80bc6c4138bc26aa84e88bc283ac87b01967ca90505152373209fd6cdcd54b5ec162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339b06fe1c6db24500898cfda885bd0651c95a4d937d01a8e2299092f22fd5bc5f951a65ceaf557fe8e5e822526e766bc2d7f562a8af0b1804a1e28b233b35f8"} 09:13:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:28 INFO - Contents: 09:13:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df46b58c59ef74ee5f55fe8f7d504345f1645a3eb9441989386077bad36e72806c13b849c806d118f349cd93af0a5bcb7d1481239070e25d70477a46cafa58bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165baba936983d62bb306790749f1f5f15d72ae3aabfceab9e64418fcaad80bc6c4138bc26aa84e88bc283ac87b01967ca90505152373209fd6cdcd54b5ec162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339b06fe1c6db24500898cfda885bd0651c95a4d937d01a8e2299092f22fd5bc5f951a65ceaf557fe8e5e822526e766bc2d7f562a8af0b1804a1e28b233b35f8"} 09:13:28 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:28 INFO - Copying logs to upload dir... 09:13:28 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.763788 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-05 09:13:28.805780) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:13:28.809163) ========= 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.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df46b58c59ef74ee5f55fe8f7d504345f1645a3eb9441989386077bad36e72806c13b849c806d118f349cd93af0a5bcb7d1481239070e25d70477a46cafa58bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165baba936983d62bb306790749f1f5f15d72ae3aabfceab9e64418fcaad80bc6c4138bc26aa84e88bc283ac87b01967ca90505152373209fd6cdcd54b5ec162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339b06fe1c6db24500898cfda885bd0651c95a4d937d01a8e2299092f22fd5bc5f951a65ceaf557fe8e5e822526e766bc2d7f562a8af0b1804a1e28b233b35f8"} build_url:https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010760 build_url: 'https://queue.taskcluster.net/v1/task/BurZjFnTR0CUV_u1R9wyCQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df46b58c59ef74ee5f55fe8f7d504345f1645a3eb9441989386077bad36e72806c13b849c806d118f349cd93af0a5bcb7d1481239070e25d70477a46cafa58bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165baba936983d62bb306790749f1f5f15d72ae3aabfceab9e64418fcaad80bc6c4138bc26aa84e88bc283ac87b01967ca90505152373209fd6cdcd54b5ec162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339b06fe1c6db24500898cfda885bd0651c95a4d937d01a8e2299092f22fd5bc5f951a65ceaf557fe8e5e822526e766bc2d7f562a8af0b1804a1e28b233b35f8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:13:28.840308) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:13:28.840618) ========= 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.emFyTzBNyS/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.WIR4BCRmoX/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.004937 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:13:28.888698) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:13:28.889022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:13:28.889344) ========= ========= Total master_lag: 0.20 =========