builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0218 starttime: 1475982840.08 results: success (0) buildid: 20161008171719 builduid: af0787e223ab47cb84442e656c23ce9a revision: ea311deedce9154009a9575e9cad1345669ff781 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.093636) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.094312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.094602) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.119664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.120271) ========= 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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-08 20:14:00-- 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% 77.7M=0s 2016-10-08 20:14:00 (77.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.619581 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.761525) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.761876) ========= 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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.021445 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:00.810939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-08 20:14:00.811289) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ea311deedce9154009a9575e9cad1345669ff781 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev ea311deedce9154009a9575e9cad1345669ff781 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-08 20:14:00,892 truncating revision to first 12 chars 2016-10-08 20:14:00,892 Setting DEBUG logging. 2016-10-08 20:14:00,892 attempt 1/10 2016-10-08 20:14:00,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ea311deedce9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-08 20:14:03,101 unpacking tar archive at: fx-team-ea311deedce9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.476245 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-08 20:14:03.306845) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:03.307234) ========= script_repo_revision: ea311deedce9154009a9575e9cad1345669ff781 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:03.307641) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:03.307978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-08 20:14:03.325146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-08 20:14:03.325574) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:03 INFO - MultiFileLogger online at 20161008 20:14:03 in /builds/slave/test 20:14:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:14:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:14:03 INFO - {'append_to_log': False, 20:14:03 INFO - 'base_work_dir': '/builds/slave/test', 20:14:03 INFO - 'blob_upload_branch': 'Fx-Team', 20:14:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:14:03 INFO - 'branch': 'Fx-Team', 20:14:03 INFO - 'buildbot_json_path': 'buildprops.json', 20:14:03 INFO - 'config_files': ('talos/mac_config.py',), 20:14:03 INFO - 'default_actions': ('clobber', 20:14:03 INFO - 'read-buildbot-config', 20:14:03 INFO - 'download-and-extract', 20:14:03 INFO - 'populate-webroot', 20:14:03 INFO - 'create-virtualenv', 20:14:03 INFO - 'install', 20:14:03 INFO - 'run-tests'), 20:14:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:14:03 INFO - 'download_minidump_stackwalk': True, 20:14:03 INFO - 'download_symbols': 'ondemand', 20:14:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:14:03 INFO - 'tooltool.py': '/tools/tooltool.py', 20:14:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:14:03 INFO - '/tools/misc-python/virtualenv.py')}, 20:14:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:14:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:14:03 INFO - 'installer_path': 'installer.exe', 20:14:03 INFO - 'log_level': 'info', 20:14:03 INFO - 'log_name': 'talos', 20:14:03 INFO - 'log_to_console': True, 20:14:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:14:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:14:03 INFO - 'opt_config_files': (), 20:14:03 INFO - 'pip_index': False, 20:14:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:14:03 INFO - 'cmd': ('bash', 20:14:03 INFO - '-c', 20:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:14:03 INFO - 'enabled': True, 20:14:03 INFO - 'halt_on_failure': False, 20:14:03 INFO - 'name': 'check_screen_resolution'},), 20:14:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:14:03 INFO - 'cmd': ('bash', 20:14:03 INFO - '-c', 20:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:14:03 INFO - 'enabled': True, 20:14:03 INFO - 'halt_on_failure': False, 20:14:03 INFO - 'name': 'check_screen_resolution'},), 20:14:03 INFO - 'run_cmd_checks_enabled': True, 20:14:03 INFO - 'sps_profile': False, 20:14:03 INFO - 'sps_profile_interval': 0, 20:14:03 INFO - 'suite': 'dromaeojs', 20:14:03 INFO - 'system_bits': '32', 20:14:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:14:03 INFO - 'title': 't-yosemite-r7-0218', 20:14:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:14:03 INFO - 'use_talos_json': True, 20:14:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:14:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:14:03 INFO - 'work_dir': 'build'} 20:14:03 INFO - [mozharness: 2016-10-09 03:14:03.513943Z] Running clobber step. 20:14:03 INFO - Running pre-action listener: _resource_record_pre_action 20:14:03 INFO - Running main action method: clobber 20:14:03 INFO - rmtree: /builds/slave/test/build 20:14:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:14:04 INFO - Running post-action listener: _resource_record_post_action 20:14:04 INFO - [mozharness: 2016-10-09 03:14:04.583371Z] Finished clobber step (success) 20:14:04 INFO - [mozharness: 2016-10-09 03:14:04.583597Z] Running read-buildbot-config step. 20:14:04 INFO - Running pre-action listener: _resource_record_pre_action 20:14:04 INFO - Running main action method: read_buildbot_config 20:14:04 INFO - Using buildbot properties: 20:14:04 INFO - { 20:14:04 INFO - "project": "", 20:14:04 INFO - "product": "firefox", 20:14:04 INFO - "script_repo_revision": "production", 20:14:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:14:04 INFO - "repository": "", 20:14:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:14:04 INFO - "buildid": "20161008171719", 20:14:04 INFO - "pgo_build": "False", 20:14:04 INFO - "basedir": "/builds/slave/test", 20:14:04 INFO - "buildnumber": 0, 20:14:04 INFO - "slavename": "t-yosemite-r7-0218", 20:14:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:14:04 INFO - "platform": "yosemite_r7", 20:14:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:14:04 INFO - "branch": "fx-team", 20:14:04 INFO - "repo_path": "integration/fx-team", 20:14:04 INFO - "revision": "ea311deedce9154009a9575e9cad1345669ff781", 20:14:04 INFO - "stage_platform": "macosx64", 20:14:04 INFO - "builduid": "af0787e223ab47cb84442e656c23ce9a", 20:14:04 INFO - "slavebuilddir": "test" 20:14:04 INFO - } 20:14:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:14:04 INFO - Running post-action listener: _resource_record_post_action 20:14:04 INFO - [mozharness: 2016-10-09 03:14:04.589320Z] Finished read-buildbot-config step (success) 20:14:04 INFO - [mozharness: 2016-10-09 03:14:04.589515Z] Running download-and-extract step. 20:14:04 INFO - Running pre-action listener: _resource_record_pre_action 20:14:04 INFO - Running main action method: download_and_extract 20:14:04 INFO - mkdir: /builds/slave/test/build/tests 20:14:04 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')]} 20:14:04 INFO - trying https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:14:04 INFO - Downloading https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/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 20:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/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 20:14:06 INFO - Downloaded 1199 bytes. 20:14:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:14:06 INFO - Using the following test package requirements: 20:14:06 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:14:06 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:14:06 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:14:06 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'jsshell-mac.zip'], 20:14:06 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:14:06 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:14:06 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:14:06 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:14:06 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:14:06 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:14:06 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:14:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:14:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:14:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:14:06 INFO - Fetch https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:14:06 INFO - Http code: 200 20:14:06 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 20:14:06 INFO - x-amz-cf-id: R8mcy_S5gGaGut_gWO30aQfe77__EDPaPm6Md0g8i1vC7y-Z57fWSQ== 20:14:06 INFO - x-amz-version-id: xMtdTY800itlApGMmjqaWdpjIJnNuzLg 20:14:06 INFO - Expected file size: 16504599 20:14:06 INFO - Obtained file size: 16504599 20:14:13 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 20:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:14:14 INFO - Http code: 200 20:14:14 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 20:14:14 INFO - x-amz-cf-id: 1Ksg7h0ASkag2Q9hl0S6SBd9NlCpW4DTG7oXjQ3FfyZh7heYToc0og== 20:14:14 INFO - x-amz-version-id: xMtdTY800itlApGMmjqaWdpjIJnNuzLg 20:14:14 INFO - Expected file size: 16504599 20:14:14 INFO - Obtained file size: 16504599 20:14:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:14:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:14:20 INFO - Fetch https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:14:21 INFO - Http code: 200 20:14:21 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 20:14:21 INFO - x-amz-cf-id: CwZ1zMq0qQkrePJ3HMINPzCTAFct1e04jF2GZSDNYRLcVAKXMRonoA== 20:14:21 INFO - x-amz-version-id: EW5wBnKj3qNSzlwgTezlZFk0wMQWnnBR 20:14:21 INFO - Expected file size: 14409006 20:14:21 INFO - Obtained file size: 14409006 20:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:22 INFO - trying https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/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 20:14:24 INFO - Downloaded 111711917 bytes. 20:14:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:14:24 INFO - mkdir: /builds/slave/test/properties 20:14:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:14:24 INFO - Writing to file /builds/slave/test/properties/build_url 20:14:24 INFO - Contents: 20:14:24 INFO - build_url:https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:14:25 INFO - Running post-action listener: _resource_record_post_action 20:14:25 INFO - Running post-action listener: set_extra_try_arguments 20:14:25 INFO - [mozharness: 2016-10-09 03:14:25.570773Z] Finished download-and-extract step (success) 20:14:25 INFO - [mozharness: 2016-10-09 03:14:25.570903Z] Running populate-webroot step. 20:14:25 INFO - Running pre-action listener: _resource_record_pre_action 20:14:25 INFO - Running main action method: populate_webroot 20:14:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:14:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:14:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:14:25 INFO - '--tppagecycles', 20:14:25 INFO - '7'], 20:14:25 INFO - 'tests': ['tsvgm']}}, 20:14:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:14:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:14:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:14:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:14:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:14:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:14:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['damp', 'tps']}, 20:14:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'tests': ['damp', 'tps']}, 20:14:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['dromaeo_dom']}, 20:14:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:14:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['basic_compositor_video']}, 20:14:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:14:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'other-e10s': {'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['a11yr', 20:14:25 INFO - 'ts_paint', 20:14:25 INFO - 'tpaint', 20:14:25 INFO - 'sessionrestore', 20:14:25 INFO - 'sessionrestore_no_auto_restore', 20:14:25 INFO - 'tabpaint']}, 20:14:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['tsvgx', 20:14:25 INFO - 'tsvgr_opacity', 20:14:25 INFO - 'tart', 20:14:25 INFO - 'tscrollx', 20:14:25 INFO - 'cart']}, 20:14:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:14:25 INFO - 'tsvgr_opacity', 20:14:25 INFO - 'tart', 20:14:25 INFO - 'tscrollx', 20:14:25 INFO - 'cart']}, 20:14:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'talos_options': ['--disable-e10s'], 20:14:25 INFO - 'tests': ['tp5o']}, 20:14:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'tests': ['tp5o']}, 20:14:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'talos_options': ['--disable-e10s', 20:14:25 INFO - '--xperf_path', 20:14:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:14:25 INFO - 'tests': ['tp5n']}, 20:14:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:14:25 INFO - 'talos_options': ['--xperf_path', 20:14:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:14:25 INFO - 'tests': ['tp5n']}}, 20:14:25 INFO - 'talos.zip': {'path': '', 20:14:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:14:25 INFO - Running post-action listener: _resource_record_post_action 20:14:25 INFO - [mozharness: 2016-10-09 03:14:25.589267Z] Finished populate-webroot step (success) 20:14:25 INFO - [mozharness: 2016-10-09 03:14:25.589374Z] Running create-virtualenv step. 20:14:25 INFO - Running pre-action listener: _resource_record_pre_action 20:14:25 INFO - Running main action method: create_virtualenv 20:14:25 INFO - Creating virtualenv /builds/slave/test/build/venv 20:14:25 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 20:14:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:14:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:14:25 INFO - Using real prefix '/tools/python27' 20:14:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:14:26 INFO - Installing distribute.............................................................................................................................................................................................done. 20:14:29 INFO - Installing pip.................done. 20:14:29 INFO - Return code: 0 20:14:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:14:29 INFO - Reading from file tmpfile_stdout 20:14:29 INFO - Output received: 20:14:29 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) 20:14:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:29 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:29 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 20:14:29 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 20:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:29 INFO - 'HOME': '/Users/cltbld', 20:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:29 INFO - 'LOGNAME': 'cltbld', 20:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:29 INFO - 'MOZ_NO_REMOTE': '1', 20:14:29 INFO - 'NO_EM_RESTART': '1', 20:14:29 INFO - 'PAGER': '/bin/cat', 20:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:29 INFO - 'PWD': '/builds/slave/test', 20:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:29 INFO - 'SHELL': '/bin/bash', 20:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:29 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:29 INFO - 'USER': 'cltbld', 20:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:29 INFO - 'XPC_FLAGS': '0x0', 20:14:29 INFO - 'XPC_SERVICE_NAME': '0', 20:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:29 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 20:14:29 INFO - Cleaning up... 20:14:29 INFO - Return code: 0 20:14:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:29 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:29 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 20:14:29 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 20:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:29 INFO - 'HOME': '/Users/cltbld', 20:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:29 INFO - 'LOGNAME': 'cltbld', 20:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:29 INFO - 'MOZ_NO_REMOTE': '1', 20:14:29 INFO - 'NO_EM_RESTART': '1', 20:14:29 INFO - 'PAGER': '/bin/cat', 20:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:29 INFO - 'PWD': '/builds/slave/test', 20:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:29 INFO - 'SHELL': '/bin/bash', 20:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:29 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:29 INFO - 'USER': 'cltbld', 20:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:29 INFO - 'XPC_FLAGS': '0x0', 20:14:29 INFO - 'XPC_SERVICE_NAME': '0', 20:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:30 INFO - Downloading/unpacking psutil>=3.1.1 20:14:30 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 20:14:30 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 20:14:30 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 20:14:30 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 20:14:30 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 20:14:30 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 20:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:14:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:14:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:14:33 INFO - Installing collected packages: psutil 20:14:33 INFO - Running setup.py install for psutil 20:14:33 INFO - building 'psutil._psutil_osx' extension 20:14:33 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 20:14:34 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 20:14:34 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 20:14:34 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 20:14:34 INFO - building 'psutil._psutil_posix' extension 20:14:34 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 20:14:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:14:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:14:34 INFO - ^ 20:14:34 INFO - 1 warning generated. 20:14:34 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 20:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:14:34 INFO - Successfully installed psutil 20:14:34 INFO - Cleaning up... 20:14:34 INFO - Return code: 0 20:14:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:34 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:34 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 20:14:34 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 20:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:34 INFO - 'HOME': '/Users/cltbld', 20:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:34 INFO - 'LOGNAME': 'cltbld', 20:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:34 INFO - 'MOZ_NO_REMOTE': '1', 20:14:34 INFO - 'NO_EM_RESTART': '1', 20:14:34 INFO - 'PAGER': '/bin/cat', 20:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:34 INFO - 'PWD': '/builds/slave/test', 20:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:34 INFO - 'SHELL': '/bin/bash', 20:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:34 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:34 INFO - 'USER': 'cltbld', 20:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:34 INFO - 'XPC_FLAGS': '0x0', 20:14:34 INFO - 'XPC_SERVICE_NAME': '0', 20:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:14:35 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 20:14:35 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 20:14:35 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 20:14:35 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 20:14:35 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 20:14:35 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 20:14:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:14:37 INFO - Installing collected packages: mozsystemmonitor 20:14:37 INFO - Running setup.py install for mozsystemmonitor 20:14:37 INFO - Successfully installed mozsystemmonitor 20:14:37 INFO - Cleaning up... 20:14:37 INFO - Return code: 0 20:14:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:37 INFO - 'HOME': '/Users/cltbld', 20:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:37 INFO - 'LOGNAME': 'cltbld', 20:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:37 INFO - 'MOZ_NO_REMOTE': '1', 20:14:37 INFO - 'NO_EM_RESTART': '1', 20:14:37 INFO - 'PAGER': '/bin/cat', 20:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:37 INFO - 'PWD': '/builds/slave/test', 20:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:37 INFO - 'SHELL': '/bin/bash', 20:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:37 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:37 INFO - 'USER': 'cltbld', 20:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:37 INFO - 'XPC_FLAGS': '0x0', 20:14:37 INFO - 'XPC_SERVICE_NAME': '0', 20:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:38 INFO - Downloading/unpacking jsonschema==2.5.1 20:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:14:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14:41 INFO - Downloading functools32-3.2.3-2.tar.gz 20:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:14:41 INFO - warning: no files found matching '*.txt' 20:14:41 INFO - no previously-included directories found matching 'build' 20:14:41 INFO - no previously-included directories found matching 'dist' 20:14:41 INFO - no previously-included directories found matching '.git*' 20:14:41 INFO - Installing collected packages: jsonschema, functools32 20:14:41 INFO - Running setup.py install for functools32 20:14:41 INFO - warning: no files found matching '*.txt' 20:14:41 INFO - no previously-included directories found matching 'build' 20:14:41 INFO - no previously-included directories found matching 'dist' 20:14:41 INFO - no previously-included directories found matching '.git*' 20:14:41 INFO - Successfully installed jsonschema functools32 20:14:41 INFO - Cleaning up... 20:14:41 INFO - Return code: 0 20:14:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:41 INFO - 'HOME': '/Users/cltbld', 20:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:41 INFO - 'LOGNAME': 'cltbld', 20:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:41 INFO - 'MOZ_NO_REMOTE': '1', 20:14:41 INFO - 'NO_EM_RESTART': '1', 20:14:41 INFO - 'PAGER': '/bin/cat', 20:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:41 INFO - 'PWD': '/builds/slave/test', 20:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:41 INFO - 'SHELL': '/bin/bash', 20:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:41 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:41 INFO - 'USER': 'cltbld', 20:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:41 INFO - 'XPC_FLAGS': '0x0', 20:14:41 INFO - 'XPC_SERVICE_NAME': '0', 20:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:14:41 INFO - Cleaning up... 20:14:41 INFO - Return code: 0 20:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:41 INFO - 'HOME': '/Users/cltbld', 20:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:41 INFO - 'LOGNAME': 'cltbld', 20:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:41 INFO - 'MOZ_NO_REMOTE': '1', 20:14:41 INFO - 'NO_EM_RESTART': '1', 20:14:41 INFO - 'PAGER': '/bin/cat', 20:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:41 INFO - 'PWD': '/builds/slave/test', 20:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:41 INFO - 'SHELL': '/bin/bash', 20:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:41 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:41 INFO - 'USER': 'cltbld', 20:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:41 INFO - 'XPC_FLAGS': '0x0', 20:14:41 INFO - 'XPC_SERVICE_NAME': '0', 20:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:42 INFO - Downloading/unpacking blobuploader==1.2.4 20:14:42 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 20:14:42 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 20:14:42 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 20:14:42 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 20:14:42 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 20:14:42 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 20:14:44 INFO - Downloading blobuploader-1.2.4.tar.gz 20:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:14:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14: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 20:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:14:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:14:45 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 20:14:45 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 20:14:45 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 20:14:45 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 20:14:45 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 20:14:45 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 20:14:45 INFO - Downloading docopt-0.6.1.tar.gz 20:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:14:45 INFO - Installing collected packages: blobuploader, requests, docopt 20:14:45 INFO - Running setup.py install for blobuploader 20:14:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:14:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:14:46 INFO - Running setup.py install for requests 20:14:46 INFO - Running setup.py install for docopt 20:14:46 INFO - Successfully installed blobuploader requests docopt 20:14:46 INFO - Cleaning up... 20:14:46 INFO - Return code: 0 20:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:46 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:46 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 20:14:46 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 20:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:46 INFO - 'HOME': '/Users/cltbld', 20:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:46 INFO - 'LOGNAME': 'cltbld', 20:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:46 INFO - 'MOZ_NO_REMOTE': '1', 20:14:46 INFO - 'NO_EM_RESTART': '1', 20:14:46 INFO - 'PAGER': '/bin/cat', 20:14:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:46 INFO - 'PWD': '/builds/slave/test', 20:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:46 INFO - 'SHELL': '/bin/bash', 20:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:46 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:46 INFO - 'USER': 'cltbld', 20:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:46 INFO - 'XPC_FLAGS': '0x0', 20:14:46 INFO - 'XPC_SERVICE_NAME': '0', 20:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-w23vWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-pOBOex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-z0sJf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Af4kGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-6LmZ5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-MXC8Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Rkdmh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-TCT4UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-t1wntA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:14:47 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-zZky2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-iPg4sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-kXLMqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Is8Xab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-_w3tpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-T1HLdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-X5_eNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:14:48 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-sr8N7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:14:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:14:48 INFO - Running setup.py install for manifestparser 20:14:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:14:48 INFO - Running setup.py install for mozcrash 20:14:49 INFO - Running setup.py install for mozdebug 20:14:49 INFO - Running setup.py install for mozdevice 20:14:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:14:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:14:49 INFO - Running setup.py install for mozfile 20:14:49 INFO - Running setup.py install for mozhttpd 20:14:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:14:49 INFO - Running setup.py install for mozinfo 20:14:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:14:49 INFO - Running setup.py install for mozInstall 20:14:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Running setup.py install for mozleak 20:14:50 INFO - Running setup.py install for mozlog 20:14:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Running setup.py install for moznetwork 20:14:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Running setup.py install for mozprocess 20:14:50 INFO - Running setup.py install for mozprofile 20:14:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:14:50 INFO - Running setup.py install for mozrunner 20:14:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:14:51 INFO - Running setup.py install for mozscreenshot 20:14:51 INFO - Running setup.py install for moztest 20:14:51 INFO - Running setup.py install for mozversion 20:14:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:14:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:14:51 INFO - Cleaning up... 20:14:51 INFO - Return code: 0 20:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14: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')]} 20:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14: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')]} 20:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:51 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:51 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 20:14:51 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 20:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:51 INFO - 'HOME': '/Users/cltbld', 20:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:51 INFO - 'LOGNAME': 'cltbld', 20:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:51 INFO - 'MOZ_NO_REMOTE': '1', 20:14:51 INFO - 'NO_EM_RESTART': '1', 20:14:51 INFO - 'PAGER': '/bin/cat', 20:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:51 INFO - 'PWD': '/builds/slave/test', 20:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:51 INFO - 'SHELL': '/bin/bash', 20:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:51 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:51 INFO - 'USER': 'cltbld', 20:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:51 INFO - 'XPC_FLAGS': '0x0', 20:14:51 INFO - 'XPC_SERVICE_NAME': '0', 20:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:14:51 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-PytG2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:14:51 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)) 20:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:14:51 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-ihS7Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:14:51 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)) 20:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:14:51 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-g2rK24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-n7Mw7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-vSTxNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-4LWQfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-cwdcA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-r24HmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-9fa00H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-h8oIPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-jHOfP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:14:52 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)) 20:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:14:52 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-p2p5Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:14:53 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)) 20:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:14:53 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-A0bsoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:14:53 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)) 20:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:14:53 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-jaDFXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:14:53 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)) 20:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:53 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-fKaeIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:14:53 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)) 20:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:14:53 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-C_Nn7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:14:53 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)) 20:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:14:53 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-xukRIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:14:53 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)) 20:14:53 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)) 20:14:53 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)) 20:14:53 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)) 20:14:53 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)) 20:14:53 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)) 20:14:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:14:56 INFO - Downloading blessings-1.6.tar.gz 20:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:14:56 INFO - Installing collected packages: blessings 20:14:56 INFO - Running setup.py install for blessings 20:14:56 INFO - Successfully installed blessings 20:14:56 INFO - Cleaning up... 20:14:56 INFO - Return code: 0 20:14:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:14:56 INFO - Reading from file tmpfile_stdout 20:14:56 INFO - Current package versions: 20:14:56 INFO - blessings == 1.6 20:14:56 INFO - blobuploader == 1.2.4 20:14:56 INFO - docopt == 0.6.1 20:14:56 INFO - functools32 == 3.2.3-2 20:14:56 INFO - jsonschema == 2.5.1 20:14:56 INFO - manifestparser == 1.1 20:14:56 INFO - mozInstall == 1.12 20:14:56 INFO - mozcrash == 0.17 20:14:56 INFO - mozdebug == 0.1 20:14:56 INFO - mozdevice == 0.48 20:14:56 INFO - mozfile == 1.2 20:14:56 INFO - mozhttpd == 0.7 20:14:56 INFO - mozinfo == 0.9 20:14:56 INFO - mozleak == 0.1 20:14:56 INFO - mozlog == 3.4 20:14:56 INFO - moznetwork == 0.27 20:14:56 INFO - mozprocess == 0.23 20:14:56 INFO - mozprofile == 0.28 20:14:56 INFO - mozrunner == 6.12 20:14:56 INFO - mozscreenshot == 0.1 20:14:56 INFO - mozsystemmonitor == 0.3 20:14:56 INFO - moztest == 0.7 20:14:56 INFO - mozversion == 1.4 20:14:56 INFO - psutil == 3.1.1 20:14:56 INFO - requests == 1.2.3 20:14:56 INFO - wsgiref == 0.1.2 20:14:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:14: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')]} 20:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14: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')]} 20:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:56 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:56 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 20:14:56 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 20:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:56 INFO - 'HOME': '/Users/cltbld', 20:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:56 INFO - 'LOGNAME': 'cltbld', 20:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:56 INFO - 'MOZ_NO_REMOTE': '1', 20:14:56 INFO - 'NO_EM_RESTART': '1', 20:14:56 INFO - 'PAGER': '/bin/cat', 20:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:56 INFO - 'PWD': '/builds/slave/test', 20:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:56 INFO - 'SHELL': '/bin/bash', 20:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:56 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:56 INFO - 'USER': 'cltbld', 20:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:56 INFO - 'XPC_FLAGS': '0x0', 20:14:56 INFO - 'XPC_SERVICE_NAME': '0', 20:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 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)) 20:14:57 INFO - Cleaning up... 20:14:57 INFO - Return code: 0 20:14:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:14:57 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')]} 20:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:57 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')]} 20:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:14:57 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 0x1069e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106958570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a9cc0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, '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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 20:14:57 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 20:14:57 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 20:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:14:57 INFO - 'HOME': '/Users/cltbld', 20:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:14:57 INFO - 'LOGNAME': 'cltbld', 20:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:14:57 INFO - 'MOZ_NO_REMOTE': '1', 20:14:57 INFO - 'NO_EM_RESTART': '1', 20:14:57 INFO - 'PAGER': '/bin/cat', 20:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:14:57 INFO - 'PWD': '/builds/slave/test', 20:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:14:57 INFO - 'SHELL': '/bin/bash', 20:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:14:57 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:14:57 INFO - 'USER': 'cltbld', 20:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:14:57 INFO - 'XPC_FLAGS': '0x0', 20:14:57 INFO - 'XPC_SERVICE_NAME': '0', 20:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:14:57 INFO - Cleaning up... 20:14:57 INFO - Return code: 0 20:14:57 INFO - Running post-action listener: _resource_record_post_action 20:14:57 INFO - Running post-action listener: _start_resource_monitoring 20:14:57 INFO - Starting resource monitoring. 20:14:57 INFO - [mozharness: 2016-10-09 03:14:57.325300Z] Finished create-virtualenv step (success) 20:14:57 INFO - [mozharness: 2016-10-09 03:14:57.326288Z] Running install step. 20:14:57 INFO - Running pre-action listener: _resource_record_pre_action 20:14:57 INFO - Running main action method: install 20:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:14:57 INFO - Reading from file tmpfile_stdout 20:14:57 INFO - Detecting whether we're running mozinstall >=1.0... 20:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:14:57 INFO - Reading from file tmpfile_stdout 20:14:57 INFO - Output received: 20:14:57 INFO - Usage: mozinstall [options] installer 20:14:57 INFO - Options: 20:14:57 INFO - -h, --help show this help message and exit 20:14:57 INFO - -d DEST, --destination=DEST 20:14:57 INFO - Directory to install application into. [default: 20:14:57 INFO - "/builds/slave/test"] 20:14:57 INFO - --app=APP Application being installed. [default: firefox] 20:14:57 INFO - mkdir: /builds/slave/test/build/application 20:14:57 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'] 20:14:57 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 20:15:31 INFO - Reading from file tmpfile_stdout 20:15:31 INFO - Output received: 20:15:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:15:31 INFO - Running post-action listener: _resource_record_post_action 20:15:31 INFO - [mozharness: 2016-10-09 03:15:31.749674Z] Finished install step (success) 20:15:31 INFO - [mozharness: 2016-10-09 03:15:31.749797Z] Running run-tests step. 20:15:31 INFO - Running pre-action listener: _resource_record_pre_action 20:15:31 INFO - Running main action method: run_tests 20:15:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:15:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:15:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:15:31 INFO - 2016-10-08 20:15:31.764 screenresolution[2236:24264] starting screenresolution argv=screenresolution get 20:15:31 INFO - 2016-10-08 20:15:31.767 screenresolution[2236:24264] Display 0: 1600x1200x32@60 20:15:31 INFO - 2016-10-08 20:15:31.773 screenresolution[2237:24266] starting screenresolution argv=screenresolution list 20:15:31 INFO - Available Modes on Display 0 20:15:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:15:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:15:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:15:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:15:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:15:32 INFO - Intel Iris: 20:15:32 INFO - Chipset Model: Intel Iris 20:15:32 INFO - Type: GPU 20:15:32 INFO - Bus: Built-In 20:15:32 INFO - VRAM (Dynamic, Max): 1536 MB 20:15:32 INFO - Vendor: Intel (0x8086) 20:15:32 INFO - Device ID: 0x0a2e 20:15:32 INFO - Revision ID: 0x0009 20:15:32 INFO - Displays: 20:15:32 INFO - Display: 20:15:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:15:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:15:32 INFO - Display Serial Number: 1600x1200 60 20:15:32 INFO - Main Display: Yes 20:15:32 INFO - Mirror: Off 20:15:32 INFO - Online: Yes 20:15:32 INFO - Rotation: Supported 20:15:32 INFO - Return code: 0 20:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:15:32 INFO - Python 2.7.3 20:15:32 INFO - Return code: 0 20:15:32 INFO - grabbing minidump binary from tooltool 20:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:15:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067f6d30>, '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 0x10661cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067eeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:15:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:15:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:15:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:15:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:15:32 INFO - Return code: 0 20:15:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:15:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:15:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:15:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:15:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 20:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0218 --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 20:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fgzqeb4ewG/Render', 20:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:32 INFO - 'HOME': '/Users/cltbld', 20:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:32 INFO - 'LOGNAME': 'cltbld', 20:15:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:15:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:32 INFO - 'MOZ_NO_REMOTE': '1', 20:15:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:15:32 INFO - 'NO_EM_RESTART': '1', 20:15:32 INFO - 'PAGER': '/bin/cat', 20:15:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:32 INFO - 'PWD': '/builds/slave/test', 20:15:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:32 INFO - 'SHELL': '/bin/bash', 20:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYCzy0df5y/Listeners', 20:15:32 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 20:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:32 INFO - 'USER': 'cltbld', 20:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:32 INFO - 'XPC_FLAGS': '0x0', 20:15:32 INFO - 'XPC_SERVICE_NAME': '0', 20:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 20:15:32 INFO - mozversion application_buildid: 20161008171719 20:15:32 INFO - mozversion application_changeset: ea311deedce9154009a9575e9cad1345669ff781 20:15:32 INFO - mozversion application_display_name: Nightly 20:15:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:15:32 INFO - mozversion application_name: Firefox 20:15:32 INFO - mozversion application_remotingname: firefox 20:15:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:15:32 INFO - mozversion application_vendor: Mozilla 20:15:32 INFO - mozversion application_version: 52.0a1 20:15:32 INFO - mozversion platform_buildid: 20161008171719 20:15:32 INFO - mozversion platform_changeset: ea311deedce9154009a9575e9cad1345669ff781 20:15:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:15:32 INFO - mozversion platform_version: 52.0a1 20:15:32 INFO - using testdate: 1475982932 20:15:32 INFO - actual date: 1475982932 20:15:32 INFO - starting webserver on 'localhost:55683' 20:15:32 INFO - SUITE-START | Running 2 tests 20:15:32 INFO - TEST-START | dromaeo_css 20:15:32 INFO - operating with platform_type : mac_ 20:15:32 INFO - Initialising browser for dromaeo_css test... 20:15:32 INFO - TEST-INFO | started process 2247 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpoD_yMR/profile http://localhost:55683/getInfo.html) 20:15:34 INFO - PROCESS | 2247 | __metrics Screen width/height:1600/1200 20:15:34 INFO - PROCESS | 2247 | colorDepth:24 20:15:34 INFO - PROCESS | 2247 | Browser inner width/height: 1024/689 20:15:34 INFO - PROCESS | 2247 | __metrics 20:15:34 INFO - PROCESS | 2247 | 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" 20:15:34 INFO - PROCESS | 2247 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:15:35 INFO - TEST-INFO | 2247: exit 0 20:15:35 INFO - Browser initialized. 20:15:35 INFO - Running cycle 1/1 for dromaeo_css test... 20:15:35 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpoD_yMR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:15:47 INFO - PROCESS | 2249 | RSS: Main: 235155456 20:15:47 INFO - PROCESS | 2249 | 20:16:38 INFO - PROCESS | 2249 | 1475982998374 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] 20:18:51 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55683/tests/dromaeo/cssquery-ext.html) 20:18:53 INFO - PROCESS | 2249 | RSS: Main: 319156224 20:18:53 INFO - PROCESS | 2249 | 20:21:56 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-ext.html (next: http://localhost:55683/tests/dromaeo/cssquery-jquery.html) 20:21:56 INFO - PROCESS | 2249 | RSS: Main: 370315264 20:21:56 INFO - PROCESS | 2249 | 20:24:59 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55683/tests/dromaeo/cssquery-mootools.html) 20:24:59 INFO - PROCESS | 2249 | RSS: Main: 344788992 20:24:59 INFO - PROCESS | 2249 | 20:28:02 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55683/tests/dromaeo/cssquery-prototype.html) 20:28:02 INFO - PROCESS | 2249 | RSS: Main: 356884480 20:28:02 INFO - PROCESS | 2249 | 20:31:06 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55683/tests/dromaeo/cssquery-yui.html) 20:31:07 INFO - PROCESS | 2249 | RSS: Main: 338526208 20:31:07 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:55683/tests/dromaeo/cssquery-yui.html (next: http://localhost:55683/tests/dromaeo/cssquery-dojo.html) 20:34:10 INFO - PROCESS | 2249 | RSS: Main: 383533056 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | __start_tp_report 20:34:10 INFO - PROCESS | 2249 | _x_x_mozilla_page_load 20:34:10 INFO - PROCESS | 2249 | _x_x_mozilla_page_load_details 20:34:10 INFO - PROCESS | 2249 | |i|pagename|runs| 20:34:10 INFO - PROCESS | 2249 | |0;dojo.html;3262.9673703262974;3290.09522381345;3324.1024923270716;3328.334333133371;3329.084501762015;5512.2834031575885;5572.5263352614975;5650.660960342382;5689.8293051208475;5745.023346031175;5665.5467562594995;5714.257146570951;5779.248697669297;5785.103308987103;5789.305283366002;4279.33670281107;5069.2649565812935;5135.64050516464;5161.741912904358;5210.7655155517905;5277.683338999653;5294.841154765363;5306.655067420625;5310.336207974004;5354.5716342692685;5214.296070030573;5218.13900706383;5219.556337711299;5254;5331.866703332409;5099.4390617032095;5149.485051494858;5149.716765577892;5164.509371609691;5197.194434862602;5085.694858308476;5101.744912754348;5137.178051511778;5174.534291913708;5263.4999675031195;5630.5495560355075;5653.067243904751;5655.802046928379;5674.148877668342;5676.772929082832;6047.365026672224;6068.514518838482;6201.9689901550655;6215.782447614357;6230.158928544633;6171.783987560414;6182.041783523561;6242.469390101847;6270.811875643738;6275.09011193379;5820.930452578576;5822.796202132905;5826.533877289807;5832.475077243069;5862.560307976919;6139.500484723665;6226.844328891723;6258;6260.906086408708;6271.937280627227;6059.394060593993;6118.388161183846;6122;6137.018077107641;6156.630602163885;6234.314225435199;6245.875082498324;6251.249850018027;6255.0617407388345;6258.468030217483;6043.8186854394435;6168.537359698041;6180.783672571529;6182.969085154546;6201.379862013762;5999.700014999233;6051.909221361683;6087.8478038048515;6109.755609775648;6151.200343955257;4840.15297322967;4840.733759643183;4854.053459575349;4876;4923.187674033744;4371.672124590668;4372.038151606642;4381.364702118175;4388;4416;5565.916511252334;5579.9163012554845;5613.242212301287;5623.409541998113;5684.459976302244;5222.268882356473;6179.907301390483;6261.060840873905;6271.216097987752;6284.685765711696;5655.321361436571;5689.9146512802345;5717.056685646905;5722.971385143048;5750.856228594235;5241.02874672989;5386.976474469838;5400.135978243541;5404;5408.972955135199;6194.690265486798;6217.595856269328;6248.250209974832;6255.280642726145;6277.12120303167;6257.4994000480765;6264;6275.560710750204;6288.559800814082;6292.622442653455;6244.469220116341;6288.591241569284;6289.9371006291185;6301.4328710416285;6310.432061114522;6009.969950150221;6058.90911636317;6090.482309003784;6100.066499260575;6198.008318668992;6186.505079593531;6249.6562689052535;6277.497800175884;6283.434490895841;6289.182406287154;5515.917261241005;5877.97135501277;6102.481289090477;6133.355997620186;6139.416755408229;5960.821175364746;5999.640021598718;6022.6386416813375;6128.938710612837;6135.447809697149;5566;5899.528037757055;5900.43945825146;5961.642301461753;6007.90988135187;5960.970195148997;6115.755369785159;6117.265928088658;6122.846928826637;6132.356102609162;5836;5837.67892765902;5901.232839730807;5919.881602367843;5971.373005834324 20:34:10 INFO - PROCESS | 2249 | |1;ext.html;474.3809328825883;487.43457589196515;514.2774401965239;524.3681363956433;527.4593541619842;33018.83490582547;42519;42639;42676.71969840902;44072;39504.80247598765;41674.583254167446;43581.78209108958;44118;44323.11353772922;8852.760613514114;9108.453492790453;9882.20942324614;10108.140808031289;12132.272063676208;12116.697082572919;12344.81482777759;12356.629301120936;12359.887610115087;12361.567345142921;12101;12337.568185113523;12399.38003099846;12408.503659853595;12481.188722733039;11694;12302.876971230262;12375.814362784566;12381.752364952741;12389;12081.818772718416;12178;12191.817122743167;12195.695107622292;12198.512059517609;41352.79323603363;41746.373804392955;41760.58239417597;41764.37353439701;41797.74606761802;3445.966210136949;3502.6497350265026;3590.856365745367;3759.4548790425233;3937.8424863005444;31556.053318400485;31615.73537058515;32037;32149.357012859844;32703.50944735831;3080.476319025771;3270.16610764256;3295.47272436409;3354.815485148316;3364.158960259935;498273;500451;502653;503137;504514.95485045045;62393;62717;62896;62909;63031;34796.13009674728;35608.28783424317;35690.64309356925;66769;66996.99504507339;15991.36034558628;16453.259603317878;16562.17189140538;16566.50300490987;16573.58566035859;11482.368469734078;11754.177207595558;11762.94118529402;11922.046236301076;11939.283642981449;19355.80644193568;19668.803311966698;19675;20069.197232110844;20130.39608811738;6857.828554286183;7529.6872054841115;7866.646000929972;7893.368530517545;7929.484583502054;859.4113032572708;926.1757036237753;938.2869019545059;982.3712823792779;988.2390559269322;25520.872395637904;29196.854015729787;30168.24579385533;30451.390972180434;30890.382192356028;1018.2159737002466;1024.9692509224735;1034.6482196053378;1076.5101878645198;1077.8383242513685;15258.694826103416;15784.842151578567;15838;15883.761743573856;16186.43347482856;664805;668242;670206;670481;673616;58537;59031;59040;59185.70407148023;59249.81500370055;1133.2180795251415;1224.9203801753035;1261.3188878005774;1369.349558959506;1390.8122403475402;30023.397192336186;30850.38299233958;31459.685403146592;32264.677353227107;32584;46591.53408465872;52732.47267527429;54849;54884.725576371864;54936.48484484911;629363.7063629305;637317;637947;638201;638826.6117338768;26404.339891502867;26955.460890782466;27021.86489067542;27816;42352.5764742361;46994.84405878005;48051;48174.79563010813;48204.035919280715;48521.75739121423;32458;37591.24817503689;39318.87313863058;40798.08427778015;40900;38763.80618096892;38983.61016389913;39574;40418;40772.184556308115;37090.814545927096;38022.61977380191;38513.229735405694;38742.418863716506;38761.22477550377;32360.83819580887;34142;34563.963081107606;34579.48130778091;35001.474977874845;22369.727539915904;22676.65985010259;25197.622035669847;26033.479330412905;26107.869460652575 20:34:10 INFO - PROCESS | 2249 | |2;jquery.html;340.2887964154916;350.8070561191344;361.88781477741895;374.79199044530293;403.5845464892617;15036.323365448554;15308;16280.592985175363;20639;21144.89427552864;19940.50148746282;20286.594268114666;20897.686534701992;20948.952552372397;21142.57714845702;5288.92066619001;5796.681182534959;6117.296510901258;6126.754929802825;6131.4788242999175;5312.946870531284;6025.608335458203;6090.26916769986;6092.66490343031;6095.451409373156;5731.828045158652;5928;6025;6042.667653279068;6046.455818976291;5917.704114794265;5972.402759723993;5975.581709280352;5981.910271345933;5986.132010858423;5050.74746262689;5148.330717006803;5170.353705786777;5212.687238765686;5221.295125158131;25949.61075583868;25991.350216244555;26339.604905926426;26410.471790564083;26711.46577068467;2270.25081723031;2321.8839058047197;2352.5765362234793;2409.132712223598;2414.7464516225896;14893.70212595742;15153.696926061417;15536.145511996836;15842.683146337124;16494.6701065978;1740.7911050674;1791.3013924569427;1833.7249412588085;1835.4952388093247;1886.264356900796;118544;121746.78253217443;123627.16474023084;125206.37396812957;126356;8533.871991920127;9257.768555786024;10378.532966016548;11067.667969960914;11234.325940443436;13967.930160349133;13973.860261397256;13982.230977296347;13982.790258146137;14008.019438639198;7267.6366181691765;7867;8215.548144852091;8447.746567602982;8748.56257187151;7445.664945077485;8623.439476434012;8766.517841518778;8780.341474389446;9009.23421509179;5569.749361278752;6630.593318631883;6654.434373078246;6670.466362690973;6849.726010959505;1493.4399600149943;1629.7799797027487;1748.4230204032638;1753.482722596837;1767.8674099442337;759.7805522137002;999.5002498750624;1013.3210748798323;1029.7932943924004;1042.4058286776465;20529.2814751483;22159;22224;22255.666165007537;22796.088156473554;947.2800671489717;970.2626004236831;987.3730181334904;996.2428554298785;1010.5200029985698;11033.779324413466;11534.423278836024;11623.360715160577;11718.003969662504;11757;591529;601530;611064;613597;614144;101380.49309753404;105515;107047;113131.43434282941;114411;964.2864280432569;969.3021024862088;998.2213873179077;1000.1348833259178;1000.1848493477793;12176.57381991626;19818.603627927074;19973.900130499256;20512.17951281932;20573.382798516068;40475;52119.47880521146;52470.73764631152;53370.73314633402;53402.727781776586;69394;70025;70448.94326585003;70751.29248707448;72070.63964680143;6445;7674.270944260286;7908.327792137503;7950.403719720928;8178.141295163922;12567.937160314506;12666.52432757172;12850.742985140432;12898.355082246038;13045.478180872658;11139.33164010162;11358.94320528392;11439.313641181556;11545.191836571359;11579.305241685526;10858.837117443403;10966.780664386828;10988.791232964208;11079.612213572485;11604.6518604442;10778.73053173677;11277.379744114152;11602.419879005847;11640.01059909917;11710.355930423908;7876.645550950336;7929.643166057426;7993.560354180576;8021.195231072962;8022.719204827803;10963.780724385308;11087.556497740323;11308.49111789955;11473.713157171138;11568.363739994382 20:34:10 INFO - PROCESS | 2249 | |3;mootools.html;1333.833270841145;1401.306353355089;1550.8294087650363;1857.154994477513;1899.3162461513853;8379.706710265142;8894.822103557924;8900.332475064371;8903.821923561525;8915.509646969422;7817.257360550753;8074.838503229932;8098.109207987117;8377.91622083779;8652.653893844239;2340.438294809242;2374.442006128558;2378.227076200233;2378.5837478441204;2382.8451150675237;2112.816822579353;2259.265738634942;2327.60430726776;2349.3891588187025;2383.809295256384;2320.234322673514;2320.8607483550954;2356.4108972756812;2368.822338324624;2369.620860662286;2327.0924339507606;2345.2026311054237;2348.412896775806;2359.445530300378;2362.1614326913955;2350.189184731265;2352.470694093832;2355.787979081874;2357.1514254868234;2357.4224315042925;8617.870731939025;8671.21959023691;8706.521141337224;8731.301495880378;8748.300135989106;5076.7207803571155;5225.947740522584;5231.189165679288;5237.659552129099;5239.13554263546;3379;3463.3073385323037;3604.945925811115;3605.9278814423565;3618.5295911531593;1085.4626959655022;1089.2756317049152;1090.487470888681;1108.6673997800708;1109.4120116338354;11137.665870023913;11266.493007814668;11269.098472122292;11273;11295.87041295872;4440.422745043188;4516.525764794715;4667.696599721007;4696.459907110658;4698.647601429906;9837.458939758239;9870.309078364446;9870.950645246729;9875.111239988435;9879.604815807432;4713.95286047142;5224.660397074178;5247.606429517801;5257.842264732064;5260.973695131576;4310.719803212781;4344.23975804233;4770.356001939694;4824.975875120602;4827.300041493963;3217.8391080445886;3228.741700663941;3767.3783825668456;3821.3694740368082;3866.8066596670055;1160.6344001639457;1161.9825702614467;1172.6364826903516;1172.9824052639217;1175.0716933622343;861.5519929636554;870.4124715816924;894.4838498744696;901.2024358442694;915.3455279475074;4687.273472611751;5683.8863222736145;5690.317161940511;5709.400512946164;5938.465538101592;415.5096985557072;422.5246597577725;425.4787884841045;428.2056784664465;433.2677774560982;3813.675837553839;3837.6162383761402;3879.650831425185;3897.4348719435525;3958.3072962232072;10070.04334588213;10550.525226364833;10569.471526423647;10599.364038157735;10603.151747860153;9882.160016398542;10273.897261027294;10541.209409294333;10555.525001374957;10700.357978521313;7621.1616722161625;7954;8183.836323273501;8203.71287004964;8228.136045715233;5194;5647.887042259214;5871.73577189036;6042.577019608585;6054.303755068047;8043.87934180976;9102.954485227532;9162.26701863836;9226;9681.080297371738;9506.334556580972;9949.353292035994;9950.651727189514;10088.646897358558;10099.596016159565;6881.552699074544;7141.535800172972;7156.499045067004;7364.447666424932;7542.811429714301;7794.649240784292;7877.8818317724135;7960.04479462445;8167.714130005421;8260.710875119343;7400.111986561647;7460.589667568336;7500.587467689329;7536.057992750906;7663.27198916102;7295.489315748059;7297.160826504807;7347.7795666130105;7441.516301440389;7517.097948246245;6019.759209631741;6885.380315771605;7029.718811247493;7035.296470352924;7042.964785176041;4937.7531123444405;4985.401751789808;4993.17612593918;5014.147594908802;5083.46623604531;4873.317735517101;4996.053118036855;5002.949970500248;5151.330327057459;5180.559652429435 20:34:10 INFO - PROCESS | 2249 | |4;prototype.html;291.6704124339496;299.76168945688175;308.4217092950718;314.7560640503611;315.3267773303995;4693.647976401775;4970.751462426873;5280.366356037276;5305.522502974732;5321.680699158049;4473.888152796174;4478.910421791562;5080.441151473335;5215.530602245797;5226;3358.395488812013;4010.398440233974;4038.8586399476026;4044.61576150266;4046.9392959105635;3565.821708914557;3805.4862593549933;3856.132370216707;4050.0887300357476;4079.6532294754834;3163.604549431321;3958.782266975315;4040;4045.453863728403;4046.7976601169826;3307.9503807442907;3472.73954453417;3529.9117522061897;3759.642833930772;4047.6761859051207;3434.7252219822353;3526.1713497328105;3538.5222994895616;3543.7342199335153;3565.664489759186;3979.7413168144267;4716.457607375127;4816.012717392961;5131.948680513185;5189.42916279209;3849.634284742973;4635.559621835921;4640.767961601907;4655.837045703384;4658;5598.636088654224;5609.3268807742925;5610.859728506779;5662.801801936954;5701.857453563694;3410.28384039352;3647.1611529348133;3877.4377715231135;3908.394198899175;3938.2320447512748;4746.359241502422;5026.4973502649445;5508.91736623951;5688.544916406678;5836.5914385993;3203.727683146912;3783.45139954705;3859.9421008684894;3876.8643097491454;3938.1139243669945;3324.5678061851804;3682.392405253157;4074.898127546835;4078.0824314529586;4084.346504559256;4627.6529260305615;4649.395578574764;4658.277966915133;4684.625229981593;4703.17694403478;5294.57643388528;5544.86137846549;5572.665640061609;5579.972100139474;5586.301712285965;1348.9460421583026;1376.0230236532175;1378.855549893586;1397.189630014589;1402.186731695614;690.9378155965886;734.6629134974402;848.7920459487465;865.1305438034735;866.3069544364483;3777.792221427793;3971.1065010373013;4230.49234091911;4241.915161696748;4276;3932.8623498178;4140.199013429168;4680.719156850531;4755.9286610700865;4760.976974456578;3723.2739615775204;3774.509313789245;4179.4536021672175;4305.311150216013;4343.84888004679;1907.4320003934822;2465.617829236471;2470.5800013997305;2496.115432848188;2529.3423709835574;4006.1787333596544;4788.688735232199;4832.275158726149;4841.564259216617;4852.805887764521;3688.5798967397745;4742.434996451163;4765.26138448541;4772.212584923518;4813.3983252093485;4840.806367745321;4896.681715688469;5341.7062061586985;5956.7319470623925;6075.301340345917;5054.570361519165;5820.883582328414;5827.970860145672;5830.300363956352;5830.766769329349;5096.694198348111;5504.22940788282;5759.51044161251;5774.653520788766;5787.247657804459;5006.899862002812;5142.97428512855;5684.958547177405;5851.736671849691;5853.64878107315;3432.2495527280767;3699.3711069118854;3734.3091528067393;3875.534935807721;3911.1982043681546;4586.656545124893;4827.468978412442;5022.874428139326;5714.942620656478;5756.827295181151;5067.467915868928;5393.352797664148;5394.703291319015;5478.287822583039;5569.470900264469;4384.408104905924;5315;5525.419830917856;5529.917051244315;5537;4631.861044168546;4670.9766451167525;5260.054958780915;5397.460253974571;5404.217048066291;5274.287971123849;5444.6188766785945;5481.287432633732;5540.889182216414;5558.777648893999;4794.017226468635;5159.942211846487;5416.322959630046;5438.918416223681;5450.454954504517 20:34:10 INFO - PROCESS | 2249 | |5;yui.html;650.4276236911517;754.445482570311;773.7562667759655;807.571986846971;813.8901248331473;15154.469593564214;16935.576610584725;17582.032988185678;17882.105894705244;17974.191161397735;15811.84188158121;16342.83657163428;16475.340986360563;16481.505554833344;16504.587385315343;716.5306724095713;856.4133568505594;860.9784755381103;861.3841103610916;861.5347712235385;674.1337381464818;694.8679750847323;850.5407080176698;855.4054931822386;858.4076986879064;884.9424787388831;915.0209276074603;918.609590923858;920.0385597051064;920.8158368326328;901.900790912999;904.2856143646478;906.3066753933247;907.6097278170384;908.0102688069969;527.8126265175846;542.2029616463792;663.1644128398204;671.6071098407438;672.0591172358688;14890.925545372313;15442.305096270695;15480.22598870052;15814.288357023846;15820.129892855888;13756.587302380845;14246.216458094801;14419.639509012253;14481.710365792625;14538.418463261456;11903;11945.343006134659;11946.223495472766;11957.940210298893;12376;3481.8433170507387;3799.67702745267;3799.67702745267;3833.3483307837455;3972.7219094663387;113049.4347528257;114907.42546287215;115794;116279.41860290729;118205;9647.807043859084;10491.475426228659;11104.389258590854;11155.107591392669;11335.206535542598;18106.63786724258;22117.447063823533;22128.225512106997;22128.446788830086;22150.55698886013;7839.490433121943;8005.759827205193;8112.22933821286;8287.254147126787;8298.54358010303;3655.908602284964;4503.189425903369;4558.384618076583;4560.498345182027;4707.129181101507;3589.856405743741;3663.68858647018;3701.3522633539023;3705.2404257127223;3705.40713485841;677.478341676906;701.2776839854855;709.4182770128446;714.9857002860018;727.9235680253603;550.4605486623132;567.2172402085096;606.4693393280879;660.6127133020618;661.3221448015822;7375.44684148691;8440.603254005646;9479.620815167455;9569.28230382724;9661.13049825505;553.3912696034411;578.3271168954835;588.3322429043067;633.1931733860994;682.6994575333997;4364.302752339231;4364.6290065344165;4740.407449068866;4747.762611869462;4842.58837998774;106972;132081;154245;161212;166316;139307;140168;142291.7510361823;143834.2808285952;144753.27623362027;111656;111898;112915.43542282072;115422.84577154457;118532;9015.413998090104;9026.729198124067;9079.59141838632;9128.497932613504;9240.029796871231;59511;64008.67995660178;66918.66540667265;67465.66267168829;67980.98028529676;97217;102114.4682829773;102748.48625756823;104891;105670.41494377436;11492.977457889672;11753.059755219732;11873.050155987674;12097.516099355928;12122.878771212176;11229.71925701864;13255.204687718768;13292.667683307995;13425.66435839112;13725.382357794122;9996.950015250168;12232.571859985213;12248.265104093784;12756.61730148097;12757.170783899017;10472.476376181008;11952.668581047697;12212.511499539918;12369.814452783035;12622.053345998904;10397.740056498647;11750.23623464472;12435.875641243472;12577.685557861127;12595.559155429515;10007.599696012077;10511.853313681957;10554.894451055392;10571.36571805694;10907.236493445384;8862.48655539157;10211;10387.844182337421;10413.583456661649;10470.790584188122 20:34:10 INFO - PROCESS | 2249 | __end_tp_report 20:34:10 INFO - PROCESS | 2249 | __start_cc_report 20:34:10 INFO - PROCESS | 2249 | _x_x_mozilla_cycle_collect,2148 20:34:10 INFO - PROCESS | 2249 | __end_cc_report 20:34:10 INFO - PROCESS | 2249 | __startTimestamp1475984050899__endTimestamp 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | ------- Summary: start ------- 20:34:10 INFO - PROCESS | 2249 | Number of tests: 6 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#0] dojo.html Cycles:165 Average:5703.51 Median:5900.44 stddev:647.76 (11.0%) stddev-sans-first:620.81 20:34:10 INFO - PROCESS | 2249 | Values: 3263.0 3290.1 3324.1 3328.3 3329.1 5512.3 5572.5 5650.7 5689.8 5745.0 5665.5 5714.3 5779.2 5785.1 5789.3 4279.3 5069.3 5135.6 5161.7 5210.8 5277.7 5294.8 5306.7 5310.3 5354.6 5214.3 5218.1 5219.6 5254.0 5331.9 5099.4 5149.5 5149.7 5164.5 5197.2 5085.7 5101.7 5137.2 5174.5 5263.5 5630.5 5653.1 5655.8 5674.1 5676.8 6047.4 6068.5 6202.0 6215.8 6230.2 6171.8 6182.0 6242.5 6270.8 6275.1 5820.9 5822.8 5826.5 5832.5 5862.6 6139.5 6226.8 6258.0 6260.9 6271.9 6059.4 6118.4 6122.0 6137.0 6156.6 6234.3 6245.9 6251.2 6255.1 6258.5 6043.8 6168.5 6180.8 6183.0 6201.4 5999.7 6051.9 6087.8 6109.8 6151.2 4840.2 4840.7 4854.1 4876.0 4923.2 4371.7 4372.0 4381.4 4388.0 4416.0 5565.9 5579.9 5613.2 5623.4 5684.5 5222.3 6179.9 6261.1 6271.2 6284.7 5655.3 5689.9 5717.1 5723.0 5750.9 5241.0 5387.0 5400.1 5404.0 5409.0 6194.7 6217.6 6248.3 6255.3 6277.1 6257.5 6264.0 6275.6 6288.6 6292.6 6244.5 6288.6 6289.9 6301.4 6310.4 6010.0 6058.9 6090.5 6100.1 6198.0 6186.5 6249.7 6277.5 6283.4 6289.2 5515.9 5878.0 6102.5 6133.4 6139.4 5960.8 5999.6 6022.6 6128.9 6135.4 5566.0 5899.5 5900.4 5961.6 6007.9 5961.0 6115.8 6117.3 6122.8 6132.4 5836.0 5837.7 5901.2 5919.9 5971.4 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#1] ext.html Cycles:180 Average:73393.61 Median:19555.47 stddev:162322.23 (830.1%) stddev-sans-first:162685.26 20:34:10 INFO - PROCESS | 2249 | Values: 474.4 487.4 514.3 524.4 527.5 33018.8 42519.0 42639.0 42676.7 44072.0 39504.8 41674.6 43581.8 44118.0 44323.1 8852.8 9108.5 9882.2 10108.1 12132.3 12116.7 12344.8 12356.6 12359.9 12361.6 12101.0 12337.6 12399.4 12408.5 12481.2 11694.0 12302.9 12375.8 12381.8 12389.0 12081.8 12178.0 12191.8 12195.7 12198.5 41352.8 41746.4 41760.6 41764.4 41797.7 3446.0 3502.6 3590.9 3759.5 3937.8 31556.1 31615.7 32037.0 32149.4 32703.5 3080.5 3270.2 3295.5 3354.8 3364.2 498273.0 500451.0 502653.0 503137.0 504515.0 62393.0 62717.0 62896.0 62909.0 63031.0 34796.1 35608.3 35690.6 66769.0 66997.0 15991.4 16453.3 16562.2 16566.5 16573.6 11482.4 11754.2 11762.9 11922.0 11939.3 19355.8 19668.8 19675.0 20069.2 20130.4 6857.8 7529.7 7866.6 7893.4 7929.5 859.4 926.2 938.3 982.4 988.2 25520.9 29196.9 30168.2 30451.4 30890.4 1018.2 1025.0 1034.6 1076.5 1077.8 15258.7 15784.8 15838.0 15883.8 16186.4 664805.0 668242.0 670206.0 670481.0 673616.0 58537.0 59031.0 59040.0 59185.7 59249.8 1133.2 1224.9 1261.3 1369.3 1390.8 30023.4 30850.4 31459.7 32264.7 32584.0 46591.5 52732.5 54849.0 54884.7 54936.5 629363.7 637317.0 637947.0 638201.0 638826.6 26404.3 26955.5 27021.9 27816.0 42352.6 46994.8 48051.0 48174.8 48204.0 48521.8 32458.0 37591.2 39318.9 40798.1 40900.0 38763.8 38983.6 39574.0 40418.0 40772.2 37090.8 38022.6 38513.2 38742.4 38761.2 32360.8 34142.0 34564.0 34579.5 35001.5 22369.7 22676.7 25197.6 26033.5 26107.9 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#2] jquery.html Cycles:180 Average:34717.69 Median:2337.23 stddev:100686.97 (4308.0%) stddev-sans-first:100936.33 20:34:10 INFO - PROCESS | 2249 | Values: 340.3 350.8 361.9 374.8 403.6 15036.3 15308.0 16280.6 20639.0 21144.9 19940.5 20286.6 20897.7 20949.0 21142.6 5288.9 5796.7 6117.3 6126.8 6131.5 5312.9 6025.6 6090.3 6092.7 6095.5 5731.8 5928.0 6025.0 6042.7 6046.5 5917.7 5972.4 5975.6 5981.9 5986.1 5050.7 5148.3 5170.4 5212.7 5221.3 25949.6 25991.4 26339.6 26410.5 26711.5 2270.3 2321.9 2352.6 2409.1 2414.7 14893.7 15153.7 15536.1 15842.7 16494.7 1740.8 1791.3 1833.7 1835.5 1886.3 118544.0 121746.8 123627.2 125206.4 126356.0 8533.9 9257.8 10378.5 11067.7 11234.3 13967.9 13973.9 13982.2 13982.8 14008.0 7267.6 7867.0 8215.5 8447.7 8748.6 7445.7 8623.4 8766.5 8780.3 9009.2 5569.7 6630.6 6654.4 6670.5 6849.7 1493.4 1629.8 1748.4 1753.5 1767.9 759.8 999.5 1013.3 1029.8 1042.4 20529.3 22159.0 22224.0 22255.7 22796.1 947.3 970.3 987.4 996.2 1010.5 11033.8 11534.4 11623.4 11718.0 11757.0 591529.0 601530.0 611064.0 613597.0 614144.0 101380.5 105515.0 107047.0 113131.4 114411.0 964.3 969.3 998.2 1000.1 1000.2 12176.6 19818.6 19973.9 20512.2 20573.4 40475.0 52119.5 52470.7 53370.7 53402.7 69394.0 70025.0 70448.9 70751.3 72070.6 6445.0 7674.3 7908.3 7950.4 8178.1 12567.9 12666.5 12850.7 12898.4 13045.5 11139.3 11358.9 11439.3 11545.2 11579.3 10858.8 10966.8 10988.8 11079.6 11604.7 10778.7 11277.4 11602.4 11640.0 11710.4 7876.6 7929.6 7993.6 8021.2 8022.7 10963.8 11087.6 11308.5 11473.7 11568.4 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#3] mootools.html Cycles:180 Average:5566.78 Median:4989.29 stddev:3128.18 (62.7%) stddev-sans-first:3120.78 20:34:10 INFO - PROCESS | 2249 | Values: 1333.8 1401.3 1550.8 1857.2 1899.3 8379.7 8894.8 8900.3 8903.8 8915.5 7817.3 8074.8 8098.1 8377.9 8652.7 2340.4 2374.4 2378.2 2378.6 2382.8 2112.8 2259.3 2327.6 2349.4 2383.8 2320.2 2320.9 2356.4 2368.8 2369.6 2327.1 2345.2 2348.4 2359.4 2362.2 2350.2 2352.5 2355.8 2357.2 2357.4 8617.9 8671.2 8706.5 8731.3 8748.3 5076.7 5225.9 5231.2 5237.7 5239.1 3379.0 3463.3 3604.9 3605.9 3618.5 1085.5 1089.3 1090.5 1108.7 1109.4 11137.7 11266.5 11269.1 11273.0 11295.9 4440.4 4516.5 4667.7 4696.5 4698.6 9837.5 9870.3 9871.0 9875.1 9879.6 4714.0 5224.7 5247.6 5257.8 5261.0 4310.7 4344.2 4770.4 4825.0 4827.3 3217.8 3228.7 3767.4 3821.4 3866.8 1160.6 1162.0 1172.6 1173.0 1175.1 861.6 870.4 894.5 901.2 915.3 4687.3 5683.9 5690.3 5709.4 5938.5 415.5 422.5 425.5 428.2 433.3 3813.7 3837.6 3879.7 3897.4 3958.3 10070.0 10550.5 10569.5 10599.4 10603.2 9882.2 10273.9 10541.2 10555.5 10700.4 7621.2 7954.0 8183.8 8203.7 8228.1 5194.0 5647.9 5871.7 6042.6 6054.3 8043.9 9103.0 9162.3 9226.0 9681.1 9506.3 9949.4 9950.7 10088.6 10099.6 6881.6 7141.5 7156.5 7364.4 7542.8 7794.6 7877.9 7960.0 8167.7 8260.7 7400.1 7460.6 7500.6 7536.1 7663.3 7295.5 7297.2 7347.8 7441.5 7517.1 6019.8 6885.4 7029.7 7035.3 7043.0 4937.8 4985.4 4993.2 5014.1 5083.5 4873.3 4996.1 5002.9 5151.3 5180.6 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#4] prototype.html Cycles:180 Average:4299.24 Median:4689.14 stddev:1339.54 (28.6%) stddev-sans-first:1309.09 20:34:10 INFO - PROCESS | 2249 | Values: 291.7 299.8 308.4 314.8 315.3 4693.6 4970.8 5280.4 5305.5 5321.7 4473.9 4478.9 5080.4 5215.5 5226.0 3358.4 4010.4 4038.9 4044.6 4046.9 3565.8 3805.5 3856.1 4050.1 4079.7 3163.6 3958.8 4040.0 4045.5 4046.8 3308.0 3472.7 3529.9 3759.6 4047.7 3434.7 3526.2 3538.5 3543.7 3565.7 3979.7 4716.5 4816.0 5131.9 5189.4 3849.6 4635.6 4640.8 4655.8 4658.0 5598.6 5609.3 5610.9 5662.8 5701.9 3410.3 3647.2 3877.4 3908.4 3938.2 4746.4 5026.5 5508.9 5688.5 5836.6 3203.7 3783.5 3859.9 3876.9 3938.1 3324.6 3682.4 4074.9 4078.1 4084.3 4627.7 4649.4 4658.3 4684.6 4703.2 5294.6 5544.9 5572.7 5580.0 5586.3 1348.9 1376.0 1378.9 1397.2 1402.2 690.9 734.7 848.8 865.1 866.3 3777.8 3971.1 4230.5 4241.9 4276.0 3932.9 4140.2 4680.7 4755.9 4761.0 3723.3 3774.5 4179.5 4305.3 4343.8 1907.4 2465.6 2470.6 2496.1 2529.3 4006.2 4788.7 4832.3 4841.6 4852.8 3688.6 4742.4 4765.3 4772.2 4813.4 4840.8 4896.7 5341.7 5956.7 6075.3 5054.6 5820.9 5828.0 5830.3 5830.8 5096.7 5504.2 5759.5 5774.7 5787.2 5006.9 5143.0 5685.0 5851.7 5853.6 3432.2 3699.4 3734.3 3875.5 3911.2 4586.7 4827.5 5022.9 5714.9 5756.8 5067.5 5393.4 5394.7 5478.3 5569.5 4384.4 5315.0 5525.4 5529.9 5537.0 4631.9 4671.0 5260.1 5397.5 5404.2 5274.3 5444.6 5481.3 5540.9 5558.8 4794.0 5159.9 5416.3 5438.9 5450.5 20:34:10 INFO - PROCESS | 2249 | 20:34:10 INFO - PROCESS | 2249 | [#5] yui.html Cycles:180 Average:25599.81 Median:12816.20 stddev:41621.69 (324.8%) stddev-sans-first:41696.29 20:34:10 INFO - PROCESS | 2249 | Values: 650.4 754.4 773.8 807.6 813.9 15154.5 16935.6 17582.0 17882.1 17974.2 15811.8 16342.8 16475.3 16481.5 16504.6 716.5 856.4 861.0 861.4 861.5 674.1 694.9 850.5 855.4 858.4 884.9 915.0 918.6 920.0 920.8 901.9 904.3 906.3 907.6 908.0 527.8 542.2 663.2 671.6 672.1 14890.9 15442.3 15480.2 15814.3 15820.1 13756.6 14246.2 14419.6 14481.7 14538.4 11903.0 11945.3 11946.2 11957.9 12376.0 3481.8 3799.7 3799.7 3833.3 3972.7 113049.4 114907.4 115794.0 116279.4 118205.0 9647.8 10491.5 11104.4 11155.1 11335.2 18106.6 22117.4 22128.2 22128.4 22150.6 7839.5 8005.8 8112.2 8287.3 8298.5 3655.9 4503.2 4558.4 4560.5 4707.1 3589.9 3663.7 3701.4 3705.2 3705.4 677.5 701.3 709.4 715.0 727.9 550.5 567.2 606.5 660.6 661.3 7375.4 8440.6 9479.6 9569.3 9661.1 553.4 578.3 588.3 633.2 682.7 4364.3 4364.6 4740.4 4747.8 4842.6 106972.0 132081.0 154245.0 161212.0 166316.0 139307.0 140168.0 142291.8 143834.3 144753.3 111656.0 111898.0 112915.4 115422.8 118532.0 9015.4 9026.7 9079.6 9128.5 9240.0 59511.0 64008.7 66918.7 67465.7 67981.0 97217.0 102114.5 102748.5 104891.0 105670.4 11493.0 11753.1 11873.1 12097.5 12122.9 11229.7 13255.2 13292.7 13425.7 13725.4 9997.0 12232.6 12248.3 12756.6 12757.2 10472.5 11952.7 12212.5 12369.8 12622.1 10397.7 11750.2 12435.9 12577.7 12595.6 10007.6 10511.9 10554.9 10571.4 10907.2 8862.5 10211.0 10387.8 10413.6 10470.8 20:34:10 INFO - PROCESS | 2249 | -------- Summary: end -------- 20:34:10 INFO - PROCESS | 2249 | 20:34:11 INFO - TEST-INFO | 2249: exit 0 20:34:11 INFO - TEST-OK | dromaeo_css | took 1119168ms 20:34:11 INFO - TEST-START | kraken 20:34:11 INFO - operating with platform_type : mac_ 20:34:11 INFO - Initialising browser for kraken test... 20:34:11 INFO - TEST-INFO | started process 2254 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpOtHDc2/profile http://localhost:55683/getInfo.html) 20:34:13 INFO - PROCESS | 2254 | __metrics Screen width/height:1600/1200 20:34:13 INFO - PROCESS | 2254 | colorDepth:24 20:34:13 INFO - PROCESS | 2254 | Browser inner width/height: 1024/689 20:34:13 INFO - PROCESS | 2254 | __metrics 20:34:14 INFO - PROCESS | 2254 | 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" 20:34:14 INFO - PROCESS | 2254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:34:14 INFO - TEST-INFO | 2254: exit 0 20:34:14 INFO - Browser initialized. 20:34:14 INFO - Running cycle 1/1 for kraken test... 20:34:14 INFO - TEST-INFO | started process 2255 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpOtHDc2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:34:26 INFO - PROCESS | 2255 | RSS: Main: 235126784 20:34:26 INFO - PROCESS | 2255 | 20:34:29 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55683/tests/kraken/driver.html?testName=audio-beat-detection) 20:34:30 INFO - PROCESS | 2255 | RSS: Main: 293838848 20:34:30 INFO - PROCESS | 2255 | 20:34:33 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55683/tests/kraken/driver.html?testName=audio-dft) 20:34:33 INFO - PROCESS | 2255 | RSS: Main: 293847040 20:34:33 INFO - PROCESS | 2255 | 20:34:37 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55683/tests/kraken/driver.html?testName=audio-fft) 20:34:38 INFO - PROCESS | 2255 | RSS: Main: 382160896 20:34:38 INFO - PROCESS | 2255 | 20:34:40 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55683/tests/kraken/driver.html?testName=audio-oscillator) 20:34:40 INFO - PROCESS | 2255 | RSS: Main: 284360704 20:34:40 INFO - PROCESS | 2255 | 20:34:42 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55683/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:34:43 INFO - PROCESS | 2255 | RSS: Main: 289980416 20:34:43 INFO - PROCESS | 2255 | 20:34:46 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55683/tests/kraken/driver.html?testName=imaging-darkroom) 20:34:46 INFO - PROCESS | 2255 | RSS: Main: 327233536 20:34:46 INFO - PROCESS | 2255 | 20:34:49 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55683/tests/kraken/driver.html?testName=imaging-desaturate) 20:34:50 INFO - PROCESS | 2255 | RSS: Main: 328237056 20:34:50 INFO - PROCESS | 2255 | 20:34:52 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55683/tests/kraken/driver.html?testName=json-parse-financial) 20:34:53 INFO - PROCESS | 2255 | RSS: Main: 325156864 20:34:53 INFO - PROCESS | 2255 | 20:34:55 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55683/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:34:55 INFO - PROCESS | 2255 | RSS: Main: 300953600 20:34:55 INFO - PROCESS | 2255 | 20:34:57 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:34:58 INFO - PROCESS | 2255 | RSS: Main: 307507200 20:34:58 INFO - PROCESS | 2255 | 20:35:00 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:35:01 INFO - PROCESS | 2255 | RSS: Main: 314613760 20:35:01 INFO - PROCESS | 2255 | 20:35:04 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:35:04 INFO - PROCESS | 2255 | RSS: Main: 315944960 20:35:04 INFO - PROCESS | 2255 | 20:35:07 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:35:08 INFO - PROCESS | 2255 | RSS: Main: 317313024 20:35:08 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | Cycle 1(1): loaded http://localhost:55683/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55683/tests/kraken/driver.html?testName=ai-astar) 20:35:10 INFO - PROCESS | 2255 | RSS: Main: 361938944 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | __start_tp_report 20:35:10 INFO - PROCESS | 2255 | _x_x_mozilla_page_load 20:35:10 INFO - PROCESS | 2255 | _x_x_mozilla_page_load_details 20:35:10 INFO - PROCESS | 2255 | |i|pagename|runs| 20:35:10 INFO - PROCESS | 2255 | |0;ai-astar;114;113;111;114;111;114;113;113;113;112 20:35:10 INFO - PROCESS | 2255 | |1;audio-beat-detection;152;112;115;150;117;136;116;99;178;138 20:35:10 INFO - PROCESS | 2255 | |2;audio-dft;193;261;201;186;186;183;185;182;181;183 20:35:10 INFO - PROCESS | 2255 | |3;audio-fft;80;60;58;91;64;59;59;59;97;82 20:35:10 INFO - PROCESS | 2255 | |4;audio-oscillator;88;71;93;69;69;70;92;68;69;90 20:35:10 INFO - PROCESS | 2255 | |5;imaging-gaussian-blur;87;85;87;85;86;84;87;84;87;84 20:35:10 INFO - PROCESS | 2255 | |6;imaging-darkroom;96;96;94;95;95;96;95;96;95;94 20:35:10 INFO - PROCESS | 2255 | |7;imaging-desaturate;83;86;87;85;84;84;83;84;84;84 20:35:10 INFO - PROCESS | 2255 | |8;json-parse-financial;58;53;53;53;54;55;53;52;55;55 20:35:10 INFO - PROCESS | 2255 | |9;json-stringify-tinderbox;65;73;67;75;72;69;69;69;67;97 20:35:10 INFO - PROCESS | 2255 | |10;stanford-crypto-aes;90;87;89;85;91;86;89;91;87;112 20:35:10 INFO - PROCESS | 2255 | |11;stanford-crypto-ccm;151;148;158;145;146;146;144;145;154;154 20:35:10 INFO - PROCESS | 2255 | |12;stanford-crypto-pbkdf2;172;173;172;174;170;174;178;172;225;232 20:35:10 INFO - PROCESS | 2255 | |13;stanford-crypto-sha256-iterative;68;69;70;70;68;68;70;68;69;69 20:35:10 INFO - PROCESS | 2255 | __end_tp_report 20:35:10 INFO - PROCESS | 2255 | __start_cc_report 20:35:10 INFO - PROCESS | 2255 | _x_x_mozilla_cycle_collect,1951 20:35:10 INFO - PROCESS | 2255 | __end_cc_report 20:35:10 INFO - PROCESS | 2255 | __startTimestamp1475984110448__endTimestamp 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | ------- Summary: start ------- 20:35:10 INFO - PROCESS | 2255 | Number of tests: 14 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.14 (1.0%) stddev-sans-first:1.12 20:35:10 INFO - PROCESS | 2255 | Values: 114.0 113.0 111.0 114.0 111.0 114.0 113.0 113.0 113.0 112.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:144.00 stddev:23.91 (16.6%) stddev-sans-first:24.16 20:35:10 INFO - PROCESS | 2255 | Values: 152.0 112.0 115.0 150.0 117.0 136.0 116.0 99.0 178.0 138.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#2] audio-dft Cycles:10 Average:194.10 Median:186.00 stddev:24.27 (13.1%) stddev-sans-first:25.74 20:35:10 INFO - PROCESS | 2255 | Values: 193.0 261.0 201.0 186.0 186.0 183.0 185.0 182.0 181.0 183.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#3] audio-fft Cycles:10 Average:70.90 Median:72.00 stddev:15.09 (21.0%) stddev-sans-first:15.64 20:35:10 INFO - PROCESS | 2255 | Values: 80.0 60.0 58.0 91.0 64.0 59.0 59.0 59.0 97.0 82.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.50 stddev:11.16 (14.0%) stddev-sans-first:11.22 20:35:10 INFO - PROCESS | 2255 | Values: 88.0 71.0 93.0 69.0 69.0 70.0 92.0 68.0 69.0 90.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.35 (1.6%) stddev-sans-first:1.33 20:35:10 INFO - PROCESS | 2255 | Values: 87.0 85.0 87.0 85.0 86.0 84.0 87.0 84.0 87.0 84.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.50 stddev:0.79 (0.8%) stddev-sans-first:0.78 20:35:10 INFO - PROCESS | 2255 | Values: 96.0 96.0 94.0 95.0 95.0 96.0 95.0 96.0 95.0 94.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.26 (1.5%) stddev-sans-first:1.24 20:35:10 INFO - PROCESS | 2255 | Values: 83.0 86.0 87.0 85.0 84.0 84.0 83.0 84.0 84.0 84.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.73 (3.2%) stddev-sans-first:1.12 20:35:10 INFO - PROCESS | 2255 | Values: 58.0 53.0 53.0 53.0 54.0 55.0 53.0 52.0 55.0 55.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:70.50 stddev:9.19 (13.0%) stddev-sans-first:9.36 20:35:10 INFO - PROCESS | 2255 | Values: 65.0 73.0 67.0 75.0 72.0 69.0 69.0 69.0 67.0 97.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:89.00 stddev:7.76 (8.7%) stddev-sans-first:8.23 20:35:10 INFO - PROCESS | 2255 | Values: 90.0 87.0 89.0 85.0 91.0 86.0 89.0 91.0 87.0 112.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:149.50 stddev:4.84 (3.2%) stddev-sans-first:5.09 20:35:10 INFO - PROCESS | 2255 | Values: 151.0 148.0 158.0 145.0 146.0 146.0 144.0 145.0 154.0 154.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:174.00 stddev:23.50 (13.5%) stddev-sans-first:24.51 20:35:10 INFO - PROCESS | 2255 | Values: 172.0 173.0 172.0 174.0 170.0 174.0 178.0 172.0 225.0 232.0 20:35:10 INFO - PROCESS | 2255 | 20:35:10 INFO - PROCESS | 2255 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 20:35:10 INFO - PROCESS | 2255 | Values: 68.0 69.0 70.0 70.0 68.0 68.0 70.0 68.0 69.0 69.0 20:35:10 INFO - PROCESS | 2255 | -------- Summary: end -------- 20:35:10 INFO - PROCESS | 2255 | 20:35:11 INFO - TEST-INFO | 2255: exit 0 20:35:11 INFO - TEST-OK | kraken | took 59813ms 20:35:11 INFO - SUITE-END | took 1178s 20:35:11 INFO - Completed test suite (00:19:39) 20:35:11 INFO - javascript benchmark 20:35:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3262.9673703262974, 3290.09522381345, 3324.1024923270716, 3328.334333133371, 3329.084501762015, 5512.2834031575885, 5572.5263352614975, 5650.660960342382, 5689.8293051208475, 5745.023346031175, 5665.5467562594995, 5714.257146570951, 5779.248697669297, 5785.103308987103, 5789.305283366002, 4279.33670281107, 5069.2649565812935, 5135.64050516464, 5161.741912904358, 5210.7655155517905, 5277.683338999653, 5294.841154765363, 5306.655067420625, 5310.336207974004, 5354.5716342692685, 5214.296070030573, 5218.13900706383, 5219.556337711299, 5254.0, 5331.866703332409, 5099.4390617032095, 5149.485051494858, 5149.716765577892, 5164.509371609691, 5197.194434862602, 5085.694858308476, 5101.744912754348, 5137.178051511778, 5174.534291913708, 5263.4999675031195, 5630.5495560355075, 5653.067243904751, 5655.802046928379, 5674.148877668342, 5676.772929082832, 6047.365026672224, 6068.514518838482, 6201.9689901550655, 6215.782447614357, 6230.158928544633, 6171.783987560414, 6182.041783523561, 6242.469390101847, 6270.811875643738, 6275.09011193379, 5820.930452578576, 5822.796202132905, 5826.533877289807, 5832.475077243069, 5862.560307976919, 6139.500484723665, 6226.844328891723, 6258.0, 6260.906086408708, 6271.937280627227, 6059.394060593993, 6118.388161183846, 6122.0, 6137.018077107641, 6156.630602163885, 6234.314225435199, 6245.875082498324, 6251.249850018027, 6255.0617407388345, 6258.468030217483, 6043.8186854394435, 6168.537359698041, 6180.783672571529, 6182.969085154546, 6201.379862013762, 5999.700014999233, 6051.909221361683, 6087.8478038048515, 6109.755609775648, 6151.200343955257, 4840.15297322967, 4840.733759643183, 4854.053459575349, 4876.0, 4923.187674033744, 4371.672124590668, 4372.038151606642, 4381.364702118175, 4388.0, 4416.0, 5565.916511252334, 5579.9163012554845, 5613.242212301287, 5623.409541998113, 5684.459976302244, 5222.268882356473, 6179.907301390483, 6261.060840873905, 6271.216097987752, 6284.685765711696, 5655.321361436571, 5689.9146512802345, 5717.056685646905, 5722.971385143048, 5750.856228594235, 5241.02874672989, 5386.976474469838, 5400.135978243541, 5404.0, 5408.972955135199, 6194.690265486798, 6217.595856269328, 6248.250209974832, 6255.280642726145, 6277.12120303167, 6257.4994000480765, 6264.0, 6275.560710750204, 6288.559800814082, 6292.622442653455, 6244.469220116341, 6288.591241569284, 6289.9371006291185, 6301.4328710416285, 6310.432061114522, 6009.969950150221, 6058.90911636317, 6090.482309003784, 6100.066499260575, 6198.008318668992, 6186.505079593531, 6249.6562689052535, 6277.497800175884, 6283.434490895841, 6289.182406287154, 5515.917261241005, 5877.97135501277, 6102.481289090477, 6133.355997620186, 6139.416755408229, 5960.821175364746, 5999.640021598718, 6022.6386416813375, 6128.938710612837, 6135.447809697149, 5566.0, 5899.528037757055, 5900.43945825146, 5961.642301461753, 6007.90988135187, 5960.970195148997, 6115.755369785159, 6117.265928088658, 6122.846928826637, 6132.356102609162, 5836.0, 5837.67892765902, 5901.232839730807, 5919.881602367843, 5971.373005834324], "value": 5661.066789633043, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.3809328825883, 487.43457589196515, 514.2774401965239, 524.3681363956433, 527.4593541619842, 33018.83490582547, 42519.0, 42639.0, 42676.71969840902, 44072.0, 39504.80247598765, 41674.583254167446, 43581.78209108958, 44118.0, 44323.11353772922, 8852.760613514114, 9108.453492790453, 9882.20942324614, 10108.140808031289, 12132.272063676208, 12116.697082572919, 12344.81482777759, 12356.629301120936, 12359.887610115087, 12361.567345142921, 12101.0, 12337.568185113523, 12399.38003099846, 12408.503659853595, 12481.188722733039, 11694.0, 12302.876971230262, 12375.814362784566, 12381.752364952741, 12389.0, 12081.818772718416, 12178.0, 12191.817122743167, 12195.695107622292, 12198.512059517609, 41352.79323603363, 41746.373804392955, 41760.58239417597, 41764.37353439701, 41797.74606761802, 3445.966210136949, 3502.6497350265026, 3590.856365745367, 3759.4548790425233, 3937.8424863005444, 31556.053318400485, 31615.73537058515, 32037.0, 32149.357012859844, 32703.50944735831, 3080.476319025771, 3270.16610764256, 3295.47272436409, 3354.815485148316, 3364.158960259935, 498273.0, 500451.0, 502653.0, 503137.0, 504514.95485045045, 62393.0, 62717.0, 62896.0, 62909.0, 63031.0, 34796.13009674728, 35608.28783424317, 35690.64309356925, 66769.0, 66996.99504507339, 15991.36034558628, 16453.259603317878, 16562.17189140538, 16566.50300490987, 16573.58566035859, 11482.368469734078, 11754.177207595558, 11762.94118529402, 11922.046236301076, 11939.283642981449, 19355.80644193568, 19668.803311966698, 19675.0, 20069.197232110844, 20130.39608811738, 6857.828554286183, 7529.6872054841115, 7866.646000929972, 7893.368530517545, 7929.484583502054, 859.4113032572708, 926.1757036237753, 938.2869019545059, 982.3712823792779, 988.2390559269322, 25520.872395637904, 29196.854015729787, 30168.24579385533, 30451.390972180434, 30890.382192356028, 1018.2159737002466, 1024.9692509224735, 1034.6482196053378, 1076.5101878645198, 1077.8383242513685, 15258.694826103416, 15784.842151578567, 15838.0, 15883.761743573856, 16186.43347482856, 664805.0, 668242.0, 670206.0, 670481.0, 673616.0, 58537.0, 59031.0, 59040.0, 59185.70407148023, 59249.81500370055, 1133.2180795251415, 1224.9203801753035, 1261.3188878005774, 1369.349558959506, 1390.8122403475402, 30023.397192336186, 30850.38299233958, 31459.685403146592, 32264.677353227107, 32584.0, 46591.53408465872, 52732.47267527429, 54849.0, 54884.725576371864, 54936.48484484911, 629363.7063629305, 637317.0, 637947.0, 638201.0, 638826.6117338768, 26404.339891502867, 26955.460890782466, 27021.86489067542, 27816.0, 42352.5764742361, 46994.84405878005, 48051.0, 48174.79563010813, 48204.035919280715, 48521.75739121423, 32458.0, 37591.24817503689, 39318.87313863058, 40798.08427778015, 40900.0, 38763.80618096892, 38983.61016389913, 39574.0, 40418.0, 40772.184556308115, 37090.814545927096, 38022.61977380191, 38513.229735405694, 38742.418863716506, 38761.22477550377, 32360.83819580887, 34142.0, 34563.963081107606, 34579.48130778091, 35001.474977874845, 22369.727539915904, 22676.65985010259, 25197.622035669847, 26033.479330412905, 26107.869460652575], "value": 20703.500277980613, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.2887964154916, 350.8070561191344, 361.88781477741895, 374.79199044530293, 403.5845464892617, 15036.323365448554, 15308.0, 16280.592985175363, 20639.0, 21144.89427552864, 19940.50148746282, 20286.594268114666, 20897.686534701992, 20948.952552372397, 21142.57714845702, 5288.92066619001, 5796.681182534959, 6117.296510901258, 6126.754929802825, 6131.4788242999175, 5312.946870531284, 6025.608335458203, 6090.26916769986, 6092.66490343031, 6095.451409373156, 5731.828045158652, 5928.0, 6025.0, 6042.667653279068, 6046.455818976291, 5917.704114794265, 5972.402759723993, 5975.581709280352, 5981.910271345933, 5986.132010858423, 5050.74746262689, 5148.330717006803, 5170.353705786777, 5212.687238765686, 5221.295125158131, 25949.61075583868, 25991.350216244555, 26339.604905926426, 26410.471790564083, 26711.46577068467, 2270.25081723031, 2321.8839058047197, 2352.5765362234793, 2409.132712223598, 2414.7464516225896, 14893.70212595742, 15153.696926061417, 15536.145511996836, 15842.683146337124, 16494.6701065978, 1740.7911050674, 1791.3013924569427, 1833.7249412588085, 1835.4952388093247, 1886.264356900796, 118544.0, 121746.78253217443, 123627.16474023084, 125206.37396812957, 126356.0, 8533.871991920127, 9257.768555786024, 10378.532966016548, 11067.667969960914, 11234.325940443436, 13967.930160349133, 13973.860261397256, 13982.230977296347, 13982.790258146137, 14008.019438639198, 7267.6366181691765, 7867.0, 8215.548144852091, 8447.746567602982, 8748.56257187151, 7445.664945077485, 8623.439476434012, 8766.517841518778, 8780.341474389446, 9009.23421509179, 5569.749361278752, 6630.593318631883, 6654.434373078246, 6670.466362690973, 6849.726010959505, 1493.4399600149943, 1629.7799797027487, 1748.4230204032638, 1753.482722596837, 1767.8674099442337, 759.7805522137002, 999.5002498750624, 1013.3210748798323, 1029.7932943924004, 1042.4058286776465, 20529.2814751483, 22159.0, 22224.0, 22255.666165007537, 22796.088156473554, 947.2800671489717, 970.2626004236831, 987.3730181334904, 996.2428554298785, 1010.5200029985698, 11033.779324413466, 11534.423278836024, 11623.360715160577, 11718.003969662504, 11757.0, 591529.0, 601530.0, 611064.0, 613597.0, 614144.0, 101380.49309753404, 105515.0, 107047.0, 113131.43434282941, 114411.0, 964.2864280432569, 969.3021024862088, 998.2213873179077, 1000.1348833259178, 1000.1848493477793, 12176.57381991626, 19818.603627927074, 19973.900130499256, 20512.17951281932, 20573.382798516068, 40475.0, 52119.47880521146, 52470.73764631152, 53370.73314633402, 53402.727781776586, 69394.0, 70025.0, 70448.94326585003, 70751.29248707448, 72070.63964680143, 6445.0, 7674.270944260286, 7908.327792137503, 7950.403719720928, 8178.141295163922, 12567.937160314506, 12666.52432757172, 12850.742985140432, 12898.355082246038, 13045.478180872658, 11139.33164010162, 11358.94320528392, 11439.313641181556, 11545.191836571359, 11579.305241685526, 10858.837117443403, 10966.780664386828, 10988.791232964208, 11079.612213572485, 11604.6518604442, 10778.73053173677, 11277.379744114152, 11602.419879005847, 11640.01059909917, 11710.355930423908, 7876.645550950336, 7929.643166057426, 7993.560354180576, 8021.195231072962, 8022.719204827803, 10963.780724385308, 11087.556497740323, 11308.49111789955, 11473.713157171138, 11568.363739994382], "value": 9631.043925786596, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.833270841145, 1401.306353355089, 1550.8294087650363, 1857.154994477513, 1899.3162461513853, 8379.706710265142, 8894.822103557924, 8900.332475064371, 8903.821923561525, 8915.509646969422, 7817.257360550753, 8074.838503229932, 8098.109207987117, 8377.91622083779, 8652.653893844239, 2340.438294809242, 2374.442006128558, 2378.227076200233, 2378.5837478441204, 2382.8451150675237, 2112.816822579353, 2259.265738634942, 2327.60430726776, 2349.3891588187025, 2383.809295256384, 2320.234322673514, 2320.8607483550954, 2356.4108972756812, 2368.822338324624, 2369.620860662286, 2327.0924339507606, 2345.2026311054237, 2348.412896775806, 2359.445530300378, 2362.1614326913955, 2350.189184731265, 2352.470694093832, 2355.787979081874, 2357.1514254868234, 2357.4224315042925, 8617.870731939025, 8671.21959023691, 8706.521141337224, 8731.301495880378, 8748.300135989106, 5076.7207803571155, 5225.947740522584, 5231.189165679288, 5237.659552129099, 5239.13554263546, 3379.0, 3463.3073385323037, 3604.945925811115, 3605.9278814423565, 3618.5295911531593, 1085.4626959655022, 1089.2756317049152, 1090.487470888681, 1108.6673997800708, 1109.4120116338354, 11137.665870023913, 11266.493007814668, 11269.098472122292, 11273.0, 11295.87041295872, 4440.422745043188, 4516.525764794715, 4667.696599721007, 4696.459907110658, 4698.647601429906, 9837.458939758239, 9870.309078364446, 9870.950645246729, 9875.111239988435, 9879.604815807432, 4713.95286047142, 5224.660397074178, 5247.606429517801, 5257.842264732064, 5260.973695131576, 4310.719803212781, 4344.23975804233, 4770.356001939694, 4824.975875120602, 4827.300041493963, 3217.8391080445886, 3228.741700663941, 3767.3783825668456, 3821.3694740368082, 3866.8066596670055, 1160.6344001639457, 1161.9825702614467, 1172.6364826903516, 1172.9824052639217, 1175.0716933622343, 861.5519929636554, 870.4124715816924, 894.4838498744696, 901.2024358442694, 915.3455279475074, 4687.273472611751, 5683.8863222736145, 5690.317161940511, 5709.400512946164, 5938.465538101592, 415.5096985557072, 422.5246597577725, 425.4787884841045, 428.2056784664465, 433.2677774560982, 3813.675837553839, 3837.6162383761402, 3879.650831425185, 3897.4348719435525, 3958.3072962232072, 10070.04334588213, 10550.525226364833, 10569.471526423647, 10599.364038157735, 10603.151747860153, 9882.160016398542, 10273.897261027294, 10541.209409294333, 10555.525001374957, 10700.357978521313, 7621.1616722161625, 7954.0, 8183.836323273501, 8203.71287004964, 8228.136045715233, 5194.0, 5647.887042259214, 5871.73577189036, 6042.577019608585, 6054.303755068047, 8043.87934180976, 9102.954485227532, 9162.26701863836, 9226.0, 9681.080297371738, 9506.334556580972, 9949.353292035994, 9950.651727189514, 10088.646897358558, 10099.596016159565, 6881.552699074544, 7141.535800172972, 7156.499045067004, 7364.447666424932, 7542.811429714301, 7794.649240784292, 7877.8818317724135, 7960.04479462445, 8167.714130005421, 8260.710875119343, 7400.111986561647, 7460.589667568336, 7500.587467689329, 7536.057992750906, 7663.27198916102, 7295.489315748059, 7297.160826504807, 7347.7795666130105, 7441.516301440389, 7517.097948246245, 6019.759209631741, 6885.380315771605, 7029.718811247493, 7035.296470352924, 7042.964785176041, 4937.7531123444405, 4985.401751789808, 4993.17612593918, 5014.147594908802, 5083.46623604531, 4873.317735517101, 4996.053118036855, 5002.949970500248, 5151.330327057459, 5180.559652429435], "value": 4401.083709096071, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.6704124339496, 299.76168945688175, 308.4217092950718, 314.7560640503611, 315.3267773303995, 4693.647976401775, 4970.751462426873, 5280.366356037276, 5305.522502974732, 5321.680699158049, 4473.888152796174, 4478.910421791562, 5080.441151473335, 5215.530602245797, 5226.0, 3358.395488812013, 4010.398440233974, 4038.8586399476026, 4044.61576150266, 4046.9392959105635, 3565.821708914557, 3805.4862593549933, 3856.132370216707, 4050.0887300357476, 4079.6532294754834, 3163.604549431321, 3958.782266975315, 4040.0, 4045.453863728403, 4046.7976601169826, 3307.9503807442907, 3472.73954453417, 3529.9117522061897, 3759.642833930772, 4047.6761859051207, 3434.7252219822353, 3526.1713497328105, 3538.5222994895616, 3543.7342199335153, 3565.664489759186, 3979.7413168144267, 4716.457607375127, 4816.012717392961, 5131.948680513185, 5189.42916279209, 3849.634284742973, 4635.559621835921, 4640.767961601907, 4655.837045703384, 4658.0, 5598.636088654224, 5609.3268807742925, 5610.859728506779, 5662.801801936954, 5701.857453563694, 3410.28384039352, 3647.1611529348133, 3877.4377715231135, 3908.394198899175, 3938.2320447512748, 4746.359241502422, 5026.4973502649445, 5508.91736623951, 5688.544916406678, 5836.5914385993, 3203.727683146912, 3783.45139954705, 3859.9421008684894, 3876.8643097491454, 3938.1139243669945, 3324.5678061851804, 3682.392405253157, 4074.898127546835, 4078.0824314529586, 4084.346504559256, 4627.6529260305615, 4649.395578574764, 4658.277966915133, 4684.625229981593, 4703.17694403478, 5294.57643388528, 5544.86137846549, 5572.665640061609, 5579.972100139474, 5586.301712285965, 1348.9460421583026, 1376.0230236532175, 1378.855549893586, 1397.189630014589, 1402.186731695614, 690.9378155965886, 734.6629134974402, 848.7920459487465, 865.1305438034735, 866.3069544364483, 3777.792221427793, 3971.1065010373013, 4230.49234091911, 4241.915161696748, 4276.0, 3932.8623498178, 4140.199013429168, 4680.719156850531, 4755.9286610700865, 4760.976974456578, 3723.2739615775204, 3774.509313789245, 4179.4536021672175, 4305.311150216013, 4343.84888004679, 1907.4320003934822, 2465.617829236471, 2470.5800013997305, 2496.115432848188, 2529.3423709835574, 4006.1787333596544, 4788.688735232199, 4832.275158726149, 4841.564259216617, 4852.805887764521, 3688.5798967397745, 4742.434996451163, 4765.26138448541, 4772.212584923518, 4813.3983252093485, 4840.806367745321, 4896.681715688469, 5341.7062061586985, 5956.7319470623925, 6075.301340345917, 5054.570361519165, 5820.883582328414, 5827.970860145672, 5830.300363956352, 5830.766769329349, 5096.694198348111, 5504.22940788282, 5759.51044161251, 5774.653520788766, 5787.247657804459, 5006.899862002812, 5142.97428512855, 5684.958547177405, 5851.736671849691, 5853.64878107315, 3432.2495527280767, 3699.3711069118854, 3734.3091528067393, 3875.534935807721, 3911.1982043681546, 4586.656545124893, 4827.468978412442, 5022.874428139326, 5714.942620656478, 5756.827295181151, 5067.467915868928, 5393.352797664148, 5394.703291319015, 5478.287822583039, 5569.470900264469, 4384.408104905924, 5315.0, 5525.419830917856, 5529.917051244315, 5537.0, 4631.861044168546, 4670.9766451167525, 5260.054958780915, 5397.460253974571, 5404.217048066291, 5274.287971123849, 5444.6188766785945, 5481.287432633732, 5540.889182216414, 5558.777648893999, 4794.017226468635, 5159.942211846487, 5416.322959630046, 5438.918416223681, 5450.454954504517], "value": 3881.271436554338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.4276236911517, 754.445482570311, 773.7562667759655, 807.571986846971, 813.8901248331473, 15154.469593564214, 16935.576610584725, 17582.032988185678, 17882.105894705244, 17974.191161397735, 15811.84188158121, 16342.83657163428, 16475.340986360563, 16481.505554833344, 16504.587385315343, 716.5306724095713, 856.4133568505594, 860.9784755381103, 861.3841103610916, 861.5347712235385, 674.1337381464818, 694.8679750847323, 850.5407080176698, 855.4054931822386, 858.4076986879064, 884.9424787388831, 915.0209276074603, 918.609590923858, 920.0385597051064, 920.8158368326328, 901.900790912999, 904.2856143646478, 906.3066753933247, 907.6097278170384, 908.0102688069969, 527.8126265175846, 542.2029616463792, 663.1644128398204, 671.6071098407438, 672.0591172358688, 14890.925545372313, 15442.305096270695, 15480.22598870052, 15814.288357023846, 15820.129892855888, 13756.587302380845, 14246.216458094801, 14419.639509012253, 14481.710365792625, 14538.418463261456, 11903.0, 11945.343006134659, 11946.223495472766, 11957.940210298893, 12376.0, 3481.8433170507387, 3799.67702745267, 3799.67702745267, 3833.3483307837455, 3972.7219094663387, 113049.4347528257, 114907.42546287215, 115794.0, 116279.41860290729, 118205.0, 9647.807043859084, 10491.475426228659, 11104.389258590854, 11155.107591392669, 11335.206535542598, 18106.63786724258, 22117.447063823533, 22128.225512106997, 22128.446788830086, 22150.55698886013, 7839.490433121943, 8005.759827205193, 8112.22933821286, 8287.254147126787, 8298.54358010303, 3655.908602284964, 4503.189425903369, 4558.384618076583, 4560.498345182027, 4707.129181101507, 3589.856405743741, 3663.68858647018, 3701.3522633539023, 3705.2404257127223, 3705.40713485841, 677.478341676906, 701.2776839854855, 709.4182770128446, 714.9857002860018, 727.9235680253603, 550.4605486623132, 567.2172402085096, 606.4693393280879, 660.6127133020618, 661.3221448015822, 7375.44684148691, 8440.603254005646, 9479.620815167455, 9569.28230382724, 9661.13049825505, 553.3912696034411, 578.3271168954835, 588.3322429043067, 633.1931733860994, 682.6994575333997, 4364.302752339231, 4364.6290065344165, 4740.407449068866, 4747.762611869462, 4842.58837998774, 106972.0, 132081.0, 154245.0, 161212.0, 166316.0, 139307.0, 140168.0, 142291.7510361823, 143834.2808285952, 144753.27623362027, 111656.0, 111898.0, 112915.43542282072, 115422.84577154457, 118532.0, 9015.413998090104, 9026.729198124067, 9079.59141838632, 9128.497932613504, 9240.029796871231, 59511.0, 64008.67995660178, 66918.66540667265, 67465.66267168829, 67980.98028529676, 97217.0, 102114.4682829773, 102748.48625756823, 104891.0, 105670.41494377436, 11492.977457889672, 11753.059755219732, 11873.050155987674, 12097.516099355928, 12122.878771212176, 11229.71925701864, 13255.204687718768, 13292.667683307995, 13425.66435839112, 13725.382357794122, 9996.950015250168, 12232.571859985213, 12248.265104093784, 12756.61730148097, 12757.170783899017, 10472.476376181008, 11952.668581047697, 12212.511499539918, 12369.814452783035, 12622.053345998904, 10397.740056498647, 11750.23623464472, 12435.875641243472, 12577.685557861127, 12595.559155429515, 10007.599696012077, 10511.853313681957, 10554.894451055392, 10571.36571805694, 10907.236493445384, 8862.48655539157, 10211.0, 10387.844182337421, 10413.583456661649, 10470.790584188122], "value": 7754.590780350808, "unit": "score"}], "name": "dromaeo_css", "value": 7285.509672390862, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 111.0, 114.0, 111.0, 114.0, 113.0, 113.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 112.0, 115.0, 150.0, 117.0, 136.0, 116.0, 99.0, 178.0, 138.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 261.0, 201.0, 186.0, 186.0, 183.0, 185.0, 182.0, 181.0, 183.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 58.0, 91.0, 64.0, 59.0, 59.0, 59.0, 97.0, 82.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 93.0, 69.0, 69.0, 70.0, 92.0, 68.0, 69.0, 90.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 86.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 94.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 87.0, 85.0, 84.0, 84.0, 83.0, 84.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 53.0, 53.0, 54.0, 55.0, 53.0, 52.0, 55.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 67.0, 75.0, 72.0, 69.0, 69.0, 69.0, 67.0, 97.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 85.0, 91.0, 86.0, 89.0, 91.0, 87.0, 112.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 158.0, 145.0, 146.0, 146.0, 144.0, 145.0, 154.0, 154.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 172.0, 174.0, 170.0, 174.0, 178.0, 172.0, 225.0, 232.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 70.0, 68.0, 68.0, 70.0, 68.0, 69.0, 69.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1471.5, "alertThreshold": 2.0}]} 20:35:11 INFO - Return code: 0 20:35:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:35:11 INFO - # TBPL SUCCESS # 20:35:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:35:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:35:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:35:11 INFO - 2016-10-08 20:35:11.760 screenresolution[2259:26534] starting screenresolution argv=screenresolution get 20:35:11 INFO - 2016-10-08 20:35:11.764 screenresolution[2259:26534] Display 0: 1600x1200x32@60 20:35:11 INFO - 2016-10-08 20:35:11.771 screenresolution[2260:26537] starting screenresolution argv=screenresolution list 20:35:11 INFO - Available Modes on Display 0 20:35:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:35:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:35:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:35:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:35:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:35:12 INFO - Intel Iris: 20:35:12 INFO - Chipset Model: Intel Iris 20:35:12 INFO - Type: GPU 20:35:12 INFO - Bus: Built-In 20:35:12 INFO - VRAM (Dynamic, Max): 1536 MB 20:35:12 INFO - Vendor: Intel (0x8086) 20:35:12 INFO - Device ID: 0x0a2e 20:35:12 INFO - Revision ID: 0x0009 20:35:12 INFO - Displays: 20:35:12 INFO - Display: 20:35:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:35:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:35:12 INFO - Display Serial Number: 1600x1200 60 20:35:12 INFO - Main Display: Yes 20:35:12 INFO - Mirror: Off 20:35:12 INFO - Online: Yes 20:35:12 INFO - Rotation: Supported 20:35:12 INFO - Return code: 0 20:35:12 INFO - Running post-action listener: _resource_record_post_action 20:35:12 INFO - [mozharness: 2016-10-09 03:35:12.008374Z] Finished run-tests step (success) 20:35:12 INFO - Running post-run listener: _resource_record_post_run 20:35:12 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 512000; Write bytes: 709164032; Read time: 3; Write time: 1990 20:35:12 INFO - TinderboxPrint: CPU usage
53.4% 20:35:12 INFO - TinderboxPrint: I/O read bytes / time
512,000 / 3 20:35:12 INFO - TinderboxPrint: I/O write bytes / time
709,164,032 / 1,990 20:35:12 INFO - TinderboxPrint: CPU idle
2,262.5 (46.6%) 20:35:12 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 20:35:12 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 20:35:12 INFO - TinderboxPrint: Swap in / out
407,470,080 / 0 20:35:12 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402000384; Write bytes: 396330496; Read time: 29641; Write time: 822 20:35:12 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 512000; Write bytes: 297887232; Read time: 3; Write time: 1125 20:35:12 INFO - Running post-run listener: _upload_blobber_files 20:35:12 INFO - Blob upload gear active. 20:35:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:35:12 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 20:35:12 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:35:12 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:35:12 INFO - (blobuploader) - INFO - Open directory for files ... 20:35:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:35:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:35:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:35:14 INFO - (blobuploader) - INFO - Done attempting. 20:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:35:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:35:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:35:15 INFO - (blobuploader) - INFO - Done attempting. 20:35:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:35:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:35:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:35:17 INFO - (blobuploader) - INFO - Done attempting. 20:35:17 INFO - (blobuploader) - INFO - Iteration through files over. 20:35:17 INFO - Return code: 0 20:35:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:35:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:35:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/006011c2583fa8dd9905d13f5be8acef0ece887ecea59aa906a0524cb4870cacb27f4e8c9c6b00acfbff9071062e1a1a3574a5c0e59a457df4d76395298cef06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9388ed84093198f9cee125963c3171ebace5ff5ae32c20e1ac20da5108a9a8032dcaf6c98b76c378c5c5a1f41c9b056b8300494f7a2666f9912120e571b929c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8db5ba51a9163e90015b11f0267ae08ec392f935bcab3b4bbc9154ae85c98e81dd0d2f2f26783ba9e2f216f4da9c301f3bbb502ff3ecf802a7858edec4883780"} 20:35:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:35:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:35:17 INFO - Contents: 20:35:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/006011c2583fa8dd9905d13f5be8acef0ece887ecea59aa906a0524cb4870cacb27f4e8c9c6b00acfbff9071062e1a1a3574a5c0e59a457df4d76395298cef06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9388ed84093198f9cee125963c3171ebace5ff5ae32c20e1ac20da5108a9a8032dcaf6c98b76c378c5c5a1f41c9b056b8300494f7a2666f9912120e571b929c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8db5ba51a9163e90015b11f0267ae08ec392f935bcab3b4bbc9154ae85c98e81dd0d2f2f26783ba9e2f216f4da9c301f3bbb502ff3ecf802a7858edec4883780"} 20:35:17 INFO - Running post-run listener: copy_logs_to_upload_dir 20:35:17 INFO - Copying logs to upload dir... 20:35:17 INFO - mkdir: /builds/slave/test/build/upload/logs 20:35:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.519855 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-08 20:35:17.890283) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 20:35:17.895132) ========= 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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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/Fx-Team/sha512/006011c2583fa8dd9905d13f5be8acef0ece887ecea59aa906a0524cb4870cacb27f4e8c9c6b00acfbff9071062e1a1a3574a5c0e59a457df4d76395298cef06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9388ed84093198f9cee125963c3171ebace5ff5ae32c20e1ac20da5108a9a8032dcaf6c98b76c378c5c5a1f41c9b056b8300494f7a2666f9912120e571b929c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8db5ba51a9163e90015b11f0267ae08ec392f935bcab3b4bbc9154ae85c98e81dd0d2f2f26783ba9e2f216f4da9c301f3bbb502ff3ecf802a7858edec4883780"} build_url:https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011497 build_url: 'https://queue.taskcluster.net/v1/task/T7pJ-V8_STS9nDtqFXIfwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/006011c2583fa8dd9905d13f5be8acef0ece887ecea59aa906a0524cb4870cacb27f4e8c9c6b00acfbff9071062e1a1a3574a5c0e59a457df4d76395298cef06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9388ed84093198f9cee125963c3171ebace5ff5ae32c20e1ac20da5108a9a8032dcaf6c98b76c378c5c5a1f41c9b056b8300494f7a2666f9912120e571b929c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8db5ba51a9163e90015b11f0267ae08ec392f935bcab3b4bbc9154ae85c98e81dd0d2f2f26783ba9e2f216f4da9c301f3bbb502ff3ecf802a7858edec4883780"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-08 20:35:17.935944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:35:17.936552) ========= 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.Fgzqeb4ewG/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.VYCzy0df5y/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.005131 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-08 20:35:17.988837) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 20:35:17.989425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-08 20:35:17.990063) ========= ========= Total master_lag: 0.19 =========