builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1477758552.69 results: success (0) buildid: 20161029063802 builduid: f503ea2dff4744db9856598a7952b4cc revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.685710) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.686206) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.686486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.712251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.712673) ========= 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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-29 09:29:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.8M=0.001s 2016-10-29 09:29:12 (15.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.858696) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.859071) ========= 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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.027099 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:12.908937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 09:29:12.909299) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --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 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-29 09:29:12,949 truncating revision to first 12 chars 2016-10-29 09:29:12,950 Setting DEBUG logging. 2016-10-29 09:29:12,950 attempt 1/10 2016-10-29 09:29:12,950 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/969c3295d3aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 09:29:14,192 unpacking tar archive at: fx-team-969c3295d3aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.940311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 09:29:14.872259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:14.872662) ========= script_repo_revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:14.873136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:14.873451) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 09:29:14.892799) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-29 09:29:14.893229) ========= /tools/buildbot/bin/python -u 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', '-u', '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:15 INFO - MultiFileLogger online at 20161029 09:29:15 in /builds/slave/test 09:29:15 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 09:29:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:15 INFO - {'append_to_log': False, 09:29:15 INFO - 'base_work_dir': '/builds/slave/test', 09:29:15 INFO - 'blob_upload_branch': 'Fx-Team', 09:29:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:15 INFO - 'branch': 'Fx-Team', 09:29:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:15 INFO - 'config_files': ('talos/mac_config.py',), 09:29:15 INFO - 'default_actions': ('clobber', 09:29:15 INFO - 'read-buildbot-config', 09:29:15 INFO - 'download-and-extract', 09:29:15 INFO - 'populate-webroot', 09:29:15 INFO - 'create-virtualenv', 09:29:15 INFO - 'install', 09:29:15 INFO - 'run-tests'), 09:29:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:15 INFO - 'download_minidump_stackwalk': True, 09:29:15 INFO - 'download_symbols': 'ondemand', 09:29:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:15 INFO - 'installer_path': 'installer.exe', 09:29:15 INFO - 'log_level': 'info', 09:29:15 INFO - 'log_name': 'talos', 09:29:15 INFO - 'log_to_console': True, 09:29:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:15 INFO - 'opt_config_files': (), 09:29:15 INFO - 'pip_index': False, 09:29:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:15 INFO - 'cmd': ('bash', 09:29:15 INFO - '-c', 09:29:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:15 INFO - 'enabled': True, 09:29:15 INFO - 'halt_on_failure': False, 09:29:15 INFO - 'name': 'check_screen_resolution'},), 09:29:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:15 INFO - 'cmd': ('bash', 09:29:15 INFO - '-c', 09:29:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:15 INFO - 'enabled': True, 09:29:15 INFO - 'halt_on_failure': False, 09:29:15 INFO - 'name': 'check_screen_resolution'},), 09:29:15 INFO - 'run_cmd_checks_enabled': True, 09:29:15 INFO - 'sps_profile': False, 09:29:15 INFO - 'sps_profile_interval': 0, 09:29:15 INFO - 'suite': 'dromaeojs', 09:29:15 INFO - 'system_bits': '32', 09:29:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:15 INFO - 'title': 't-yosemite-r7-0171', 09:29:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:15 INFO - 'use_talos_json': True, 09:29:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:15 INFO - 'work_dir': 'build'} 09:29:15 INFO - [mozharness: 2016-10-29 16:29:15.064219Z] Running clobber step. 09:29:15 INFO - Running pre-action listener: _resource_record_pre_action 09:29:15 INFO - Running main action method: clobber 09:29:15 INFO - rmtree: /builds/slave/test/build 09:29:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:17 INFO - Running post-action listener: _resource_record_post_action 09:29:17 INFO - [mozharness: 2016-10-29 16:29:17.240588Z] Finished clobber step (success) 09:29:17 INFO - [mozharness: 2016-10-29 16:29:17.240737Z] Running read-buildbot-config step. 09:29:17 INFO - Running pre-action listener: _resource_record_pre_action 09:29:17 INFO - Running main action method: read_buildbot_config 09:29:17 INFO - Using buildbot properties: 09:29:17 INFO - { 09:29:17 INFO - "project": "", 09:29:17 INFO - "product": "firefox", 09:29:17 INFO - "script_repo_revision": "production", 09:29:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:29:17 INFO - "repository": "", 09:29:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:29:17 INFO - "buildid": "20161029063802", 09:29:17 INFO - "pgo_build": "False", 09:29:17 INFO - "basedir": "/builds/slave/test", 09:29:17 INFO - "buildnumber": 16, 09:29:17 INFO - "slavename": "t-yosemite-r7-0171", 09:29:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:29:17 INFO - "platform": "yosemite_r7", 09:29:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:29:17 INFO - "branch": "fx-team", 09:29:17 INFO - "repo_path": "integration/fx-team", 09:29:17 INFO - "revision": "969c3295d3aa77931cca26eddb047d9d74bd9858", 09:29:17 INFO - "stage_platform": "macosx64", 09:29:17 INFO - "builduid": "f503ea2dff4744db9856598a7952b4cc", 09:29:17 INFO - "slavebuilddir": "test" 09:29:17 INFO - } 09:29:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:29:17 INFO - Running post-action listener: _resource_record_post_action 09:29:17 INFO - [mozharness: 2016-10-29 16:29:17.244056Z] Finished read-buildbot-config step (success) 09:29:17 INFO - [mozharness: 2016-10-29 16:29:17.244139Z] Running download-and-extract step. 09:29:17 INFO - Running pre-action listener: _resource_record_pre_action 09:29:17 INFO - Running main action method: download_and_extract 09:29:17 INFO - mkdir: /builds/slave/test/build/tests 09:29:17 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')]} 09:29:17 INFO - trying https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:29:17 INFO - Downloading https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/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 09:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/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 09:29:18 INFO - Downloaded 1199 bytes. 09:29:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:29:18 INFO - Using the following test package requirements: 09:29:18 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:29:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:29:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'jsshell-mac.zip'], 09:29:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:29:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:29:18 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:29:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:29:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:29:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:29:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:29:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:18 INFO - Fetch https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:29:19 INFO - Http code: 200 09:29:19 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:29:19 INFO - x-amz-cf-id: KLFpNso_XR6uKYZI4g2oVn-Aa9OH051Z5F_9XRhV88GBApOspc1o7Q== 09:29:19 INFO - x-amz-version-id: fAonftSKW_qXcSMN7Z6NnXjP91X1FVCK 09:29:19 INFO - Expected file size: 16391717 09:29:19 INFO - Obtained file size: 16391717 09:29:26 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 09:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:29:26 INFO - Http code: 200 09:29:26 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 09:29:26 INFO - x-amz-cf-id: WVTCI8u0RVqNIkW_vnfO2XQib8iP_lck3ZP4bxsQghVPXb3iXAGBZg== 09:29:26 INFO - x-amz-version-id: fAonftSKW_qXcSMN7Z6NnXjP91X1FVCK 09:29:27 INFO - Expected file size: 16391717 09:29:27 INFO - Obtained file size: 16391717 09:29:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:29:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:29:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:29:34 INFO - Http code: 200 09:29:34 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:29:34 INFO - x-amz-cf-id: uUssZpiicDoQh_2S8iZelmr0bGRDspYsHc8G1BdL1xX87mY2Eifi6w== 09:29:34 INFO - x-amz-version-id: v2.gT3E8OzjqD0PCAWv82kRx_qaJ2WDb 09:29:34 INFO - Expected file size: 14409038 09:29:34 INFO - Obtained file size: 14409038 09:29:35 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')]} 09:29:35 INFO - trying https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:29:35 INFO - Downloading https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/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 09:29:38 INFO - Downloaded 112027818 bytes. 09:29:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:29:38 INFO - mkdir: /builds/slave/test/properties 09:29:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:38 INFO - Contents: 09:29:38 INFO - build_url:https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:29:38 INFO - Running post-action listener: _resource_record_post_action 09:29:38 INFO - Running post-action listener: set_extra_try_arguments 09:29:38 INFO - [mozharness: 2016-10-29 16:29:38.697404Z] Finished download-and-extract step (success) 09:29:38 INFO - [mozharness: 2016-10-29 16:29:38.697557Z] Running populate-webroot step. 09:29:38 INFO - Running pre-action listener: _resource_record_pre_action 09:29:38 INFO - Running main action method: populate_webroot 09:29:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:38 INFO - '--tppagecycles', 09:29:38 INFO - '7'], 09:29:38 INFO - 'tests': ['tsvgm']}}, 09:29:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['damp', 'tps']}, 09:29:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'tests': ['damp', 'tps']}, 09:29:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['dromaeo_dom']}, 09:29:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['basic_compositor_video']}, 09:29:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['a11yr', 09:29:38 INFO - 'ts_paint', 09:29:38 INFO - 'tpaint', 09:29:38 INFO - 'sessionrestore', 09:29:38 INFO - 'sessionrestore_no_auto_restore', 09:29:38 INFO - 'tabpaint']}, 09:29:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['tsvgx', 09:29:38 INFO - 'tsvgr_opacity', 09:29:38 INFO - 'tart', 09:29:38 INFO - 'tscrollx', 09:29:38 INFO - 'cart']}, 09:29:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:38 INFO - 'tsvgr_opacity', 09:29:38 INFO - 'tart', 09:29:38 INFO - 'tscrollx', 09:29:38 INFO - 'cart']}, 09:29:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'talos_options': ['--disable-e10s'], 09:29:38 INFO - 'tests': ['tp5o']}, 09:29:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'tests': ['tp5o']}, 09:29:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'talos_options': ['--disable-e10s', 09:29:38 INFO - '--xperf_path', 09:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:38 INFO - 'tests': ['tp5n']}, 09:29:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:38 INFO - 'talos_options': ['--xperf_path', 09:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:38 INFO - 'tests': ['tp5n']}}, 09:29:38 INFO - 'talos.zip': {'path': '', 09:29:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:38 INFO - Running post-action listener: _resource_record_post_action 09:29:38 INFO - [mozharness: 2016-10-29 16:29:38.716082Z] Finished populate-webroot step (success) 09:29:38 INFO - [mozharness: 2016-10-29 16:29:38.716196Z] Running create-virtualenv step. 09:29:38 INFO - Running pre-action listener: _resource_record_pre_action 09:29:38 INFO - Running main action method: create_virtualenv 09:29:38 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:38 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 09:29:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:38 INFO - Using real prefix '/tools/python27' 09:29:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:39 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:42 INFO - Installing pip.................done. 09:29:42 INFO - Return code: 0 09:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:29:42 INFO - Reading from file tmpfile_stdout 09:29:42 INFO - Output received: 09:29:42 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) 09:29:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:42 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')]} 09:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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')]} 09:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:42 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 09:29:42 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 09:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:42 INFO - 'HOME': '/Users/cltbld', 09:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:42 INFO - 'LOGNAME': 'cltbld', 09:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:42 INFO - 'MOZ_NO_REMOTE': '1', 09:29:42 INFO - 'NO_EM_RESTART': '1', 09:29:42 INFO - 'PAGER': '/bin/cat', 09:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:42 INFO - 'PWD': '/builds/slave/test', 09:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:42 INFO - 'SHELL': '/bin/bash', 09:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:42 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:42 INFO - 'USER': 'cltbld', 09:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:42 INFO - 'XPC_FLAGS': '0x0', 09:29:42 INFO - 'XPC_SERVICE_NAME': '0', 09:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:42 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 09:29:42 INFO - Cleaning up... 09:29:42 INFO - Return code: 0 09:29:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:29:42 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')]} 09:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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')]} 09:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:42 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 09:29:42 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 09:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:42 INFO - 'HOME': '/Users/cltbld', 09:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:42 INFO - 'LOGNAME': 'cltbld', 09:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:42 INFO - 'MOZ_NO_REMOTE': '1', 09:29:42 INFO - 'NO_EM_RESTART': '1', 09:29:42 INFO - 'PAGER': '/bin/cat', 09:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:42 INFO - 'PWD': '/builds/slave/test', 09:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:42 INFO - 'SHELL': '/bin/bash', 09:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:42 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:42 INFO - 'USER': 'cltbld', 09:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:42 INFO - 'XPC_FLAGS': '0x0', 09:29:42 INFO - 'XPC_SERVICE_NAME': '0', 09:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:43 INFO - Downloading/unpacking psutil>=3.1.1 09:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:46 INFO - Installing collected packages: psutil 09:29:46 INFO - Running setup.py install for psutil 09:29:46 INFO - building 'psutil._psutil_osx' extension 09:29:46 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 09:29:47 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 09:29:47 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 09:29:47 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 09:29:47 INFO - building 'psutil._psutil_posix' extension 09:29:47 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 09:29:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:47 INFO - ^ 09:29:47 INFO - 1 warning generated. 09:29:47 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 09:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:47 INFO - Successfully installed psutil 09:29:47 INFO - Cleaning up... 09:29:47 INFO - Return code: 0 09:29:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:47 INFO - 'HOME': '/Users/cltbld', 09:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:47 INFO - 'LOGNAME': 'cltbld', 09:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:47 INFO - 'MOZ_NO_REMOTE': '1', 09:29:47 INFO - 'NO_EM_RESTART': '1', 09:29:47 INFO - 'PAGER': '/bin/cat', 09:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:47 INFO - 'PWD': '/builds/slave/test', 09:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:47 INFO - 'SHELL': '/bin/bash', 09:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:47 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:47 INFO - 'USER': 'cltbld', 09:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:47 INFO - 'XPC_FLAGS': '0x0', 09:29:47 INFO - 'XPC_SERVICE_NAME': '0', 09:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:29:50 INFO - Installing collected packages: mozsystemmonitor 09:29:50 INFO - Running setup.py install for mozsystemmonitor 09:29:51 INFO - Successfully installed mozsystemmonitor 09:29:51 INFO - Cleaning up... 09:29:51 INFO - Return code: 0 09:29:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:29: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')]} 09:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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')]} 09:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:51 INFO - 'HOME': '/Users/cltbld', 09:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:51 INFO - 'LOGNAME': 'cltbld', 09:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:51 INFO - 'MOZ_NO_REMOTE': '1', 09:29:51 INFO - 'NO_EM_RESTART': '1', 09:29:51 INFO - 'PAGER': '/bin/cat', 09:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:51 INFO - 'PWD': '/builds/slave/test', 09:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:51 INFO - 'SHELL': '/bin/bash', 09:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:51 INFO - 'USER': 'cltbld', 09:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:51 INFO - 'XPC_FLAGS': '0x0', 09:29:51 INFO - 'XPC_SERVICE_NAME': '0', 09:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:54 INFO - warning: no files found matching '*.txt' 09:29:54 INFO - no previously-included directories found matching 'build' 09:29:54 INFO - no previously-included directories found matching 'dist' 09:29:54 INFO - no previously-included directories found matching '.git*' 09:29:54 INFO - Installing collected packages: jsonschema, functools32 09:29:54 INFO - Running setup.py install for functools32 09:29:54 INFO - warning: no files found matching '*.txt' 09:29:54 INFO - no previously-included directories found matching 'build' 09:29:54 INFO - no previously-included directories found matching 'dist' 09:29:54 INFO - no previously-included directories found matching '.git*' 09:29:54 INFO - Successfully installed jsonschema functools32 09:29:54 INFO - Cleaning up... 09:29:54 INFO - Return code: 0 09:29:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:54 INFO - 'HOME': '/Users/cltbld', 09:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:54 INFO - 'LOGNAME': 'cltbld', 09:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:54 INFO - 'MOZ_NO_REMOTE': '1', 09:29:54 INFO - 'NO_EM_RESTART': '1', 09:29:54 INFO - 'PAGER': '/bin/cat', 09:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:54 INFO - 'PWD': '/builds/slave/test', 09:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:54 INFO - 'SHELL': '/bin/bash', 09:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:54 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:54 INFO - 'USER': 'cltbld', 09:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:54 INFO - 'XPC_FLAGS': '0x0', 09:29:54 INFO - 'XPC_SERVICE_NAME': '0', 09:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:29:54 INFO - Cleaning up... 09:29:54 INFO - Return code: 0 09:29:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:54 INFO - 'HOME': '/Users/cltbld', 09:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:54 INFO - 'LOGNAME': 'cltbld', 09:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:54 INFO - 'MOZ_NO_REMOTE': '1', 09:29:54 INFO - 'NO_EM_RESTART': '1', 09:29:54 INFO - 'PAGER': '/bin/cat', 09:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:54 INFO - 'PWD': '/builds/slave/test', 09:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:54 INFO - 'SHELL': '/bin/bash', 09:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:54 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:54 INFO - 'USER': 'cltbld', 09:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:54 INFO - 'XPC_FLAGS': '0x0', 09:29:54 INFO - 'XPC_SERVICE_NAME': '0', 09:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:57 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 09:29:57 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 09:29:57 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 09:29:57 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 09:29:57 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 09:29:57 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 09:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:58 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 09:29:58 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 09:29:58 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 09:29:58 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 09:29:58 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 09:29:58 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 09:29:58 INFO - Downloading docopt-0.6.1.tar.gz 09:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:58 INFO - Running setup.py install for blobuploader 09:29:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:59 INFO - Running setup.py install for requests 09:29:59 INFO - Running setup.py install for docopt 09:29:59 INFO - Successfully installed blobuploader requests docopt 09:29:59 INFO - Cleaning up... 09:29:59 INFO - Return code: 0 09:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:59 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:29:59 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 09:29:59 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 09:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:59 INFO - 'HOME': '/Users/cltbld', 09:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:59 INFO - 'LOGNAME': 'cltbld', 09:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:59 INFO - 'MOZ_NO_REMOTE': '1', 09:29:59 INFO - 'NO_EM_RESTART': '1', 09:29:59 INFO - 'PAGER': '/bin/cat', 09:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:59 INFO - 'PWD': '/builds/slave/test', 09:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:59 INFO - 'SHELL': '/bin/bash', 09:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:29:59 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:59 INFO - 'USER': 'cltbld', 09:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:59 INFO - 'XPC_FLAGS': '0x0', 09:29:59 INFO - 'XPC_SERVICE_NAME': '0', 09:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-KDe52s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-8H_Doi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-7GdxXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_INWqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-6hNTGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-U2wpxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-HBf6i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ZNBOOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Ftwhnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-YAMqD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-TY3A3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-SfAaeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ew9LGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-id2Gnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-KH63Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-l1rAa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-d_rX83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:01 INFO - Running setup.py install for manifestparser 09:30:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:02 INFO - Running setup.py install for mozcrash 09:30:02 INFO - Running setup.py install for mozdebug 09:30:02 INFO - Running setup.py install for mozdevice 09:30:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:02 INFO - Running setup.py install for mozfile 09:30:02 INFO - Running setup.py install for mozhttpd 09:30:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:02 INFO - Running setup.py install for mozinfo 09:30:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:02 INFO - Running setup.py install for mozInstall 09:30:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Running setup.py install for mozleak 09:30:03 INFO - Running setup.py install for mozlog 09:30:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Running setup.py install for moznetwork 09:30:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Running setup.py install for mozprocess 09:30:03 INFO - Running setup.py install for mozprofile 09:30:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Running setup.py install for mozrunner 09:30:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:04 INFO - Running setup.py install for mozscreenshot 09:30:04 INFO - Running setup.py install for moztest 09:30:04 INFO - Running setup.py install for mozversion 09:30:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:04 INFO - Cleaning up... 09:30:04 INFO - Return code: 0 09:30:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:04 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:30:04 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 09:30:04 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 09:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:04 INFO - 'HOME': '/Users/cltbld', 09:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:04 INFO - 'LOGNAME': 'cltbld', 09:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:04 INFO - 'MOZ_NO_REMOTE': '1', 09:30:04 INFO - 'NO_EM_RESTART': '1', 09:30:04 INFO - 'PAGER': '/bin/cat', 09:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:04 INFO - 'PWD': '/builds/slave/test', 09:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:04 INFO - 'SHELL': '/bin/bash', 09:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:30:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:04 INFO - 'USER': 'cltbld', 09:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:04 INFO - 'XPC_FLAGS': '0x0', 09:30:04 INFO - 'XPC_SERVICE_NAME': '0', 09:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:04 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-z6rEBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:04 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)) 09:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:04 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dEzLiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:04 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)) 09:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:04 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-UcaqVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-fU52wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-GTUgr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Y1QTiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Ba94FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-j_vz1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-w6NWNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Gf0jvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-5LeVf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:05 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)) 09:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-rOJJXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:06 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)) 09:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9AMRCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:06 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)) 09:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EbXUBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:06 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)) 09:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_HSEjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:06 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)) 09:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-N5yOw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:06 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)) 09:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-UUTYwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:06 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)) 09:30:06 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)) 09:30:06 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)) 09:30:06 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)) 09:30:06 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)) 09:30:06 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)) 09:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:09 INFO - Downloading blessings-1.6.tar.gz 09:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:09 INFO - Installing collected packages: blessings 09:30:09 INFO - Running setup.py install for blessings 09:30:09 INFO - Successfully installed blessings 09:30:09 INFO - Cleaning up... 09:30:09 INFO - Return code: 0 09:30:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:09 INFO - Reading from file tmpfile_stdout 09:30:09 INFO - Current package versions: 09:30:09 INFO - blessings == 1.6 09:30:09 INFO - blobuploader == 1.2.4 09:30:09 INFO - docopt == 0.6.1 09:30:09 INFO - functools32 == 3.2.3-2 09:30:09 INFO - jsonschema == 2.5.1 09:30:09 INFO - manifestparser == 1.1 09:30:09 INFO - mozInstall == 1.12 09:30:09 INFO - mozcrash == 0.17 09:30:09 INFO - mozdebug == 0.1 09:30:09 INFO - mozdevice == 0.48 09:30:09 INFO - mozfile == 1.2 09:30:09 INFO - mozhttpd == 0.7 09:30:09 INFO - mozinfo == 0.9 09:30:09 INFO - mozleak == 0.1 09:30:09 INFO - mozlog == 3.4 09:30:09 INFO - moznetwork == 0.27 09:30:09 INFO - mozprocess == 0.23 09:30:09 INFO - mozprofile == 0.28 09:30:09 INFO - mozrunner == 6.12 09:30:09 INFO - mozscreenshot == 0.1 09:30:09 INFO - mozsystemmonitor == 0.3 09:30:09 INFO - moztest == 0.7 09:30:09 INFO - mozversion == 1.4 09:30:09 INFO - psutil == 3.1.1 09:30:09 INFO - requests == 1.2.3 09:30:09 INFO - wsgiref == 0.1.2 09:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:10 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')]} 09:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:10 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:30:10 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 09:30:10 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 09:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:10 INFO - 'HOME': '/Users/cltbld', 09:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:10 INFO - 'LOGNAME': 'cltbld', 09:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:10 INFO - 'MOZ_NO_REMOTE': '1', 09:30:10 INFO - 'NO_EM_RESTART': '1', 09:30:10 INFO - 'PAGER': '/bin/cat', 09:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:10 INFO - 'PWD': '/builds/slave/test', 09:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:10 INFO - 'SHELL': '/bin/bash', 09:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:30:10 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:10 INFO - 'USER': 'cltbld', 09:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:10 INFO - 'XPC_FLAGS': '0x0', 09:30:10 INFO - 'XPC_SERVICE_NAME': '0', 09:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 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)) 09:30:10 INFO - Cleaning up... 09:30:10 INFO - Return code: 0 09:30:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:10 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')]} 09:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:10 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')]} 09:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:11 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 0x103421490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103397570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2db4666b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, '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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:30:11 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 09:30:11 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 09:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:11 INFO - 'HOME': '/Users/cltbld', 09:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:11 INFO - 'LOGNAME': 'cltbld', 09:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:11 INFO - 'MOZ_NO_REMOTE': '1', 09:30:11 INFO - 'NO_EM_RESTART': '1', 09:30:11 INFO - 'PAGER': '/bin/cat', 09:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:11 INFO - 'PWD': '/builds/slave/test', 09:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:11 INFO - 'SHELL': '/bin/bash', 09:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:30:11 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:11 INFO - 'USER': 'cltbld', 09:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:11 INFO - 'XPC_FLAGS': '0x0', 09:30:11 INFO - 'XPC_SERVICE_NAME': '0', 09:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:30:11 INFO - Cleaning up... 09:30:11 INFO - Return code: 0 09:30:11 INFO - Running post-action listener: _resource_record_post_action 09:30:11 INFO - Running post-action listener: _start_resource_monitoring 09:30:11 INFO - Starting resource monitoring. 09:30:11 INFO - [mozharness: 2016-10-29 16:30:11.311038Z] Finished create-virtualenv step (success) 09:30:11 INFO - [mozharness: 2016-10-29 16:30:11.312018Z] Running install step. 09:30:11 INFO - Running pre-action listener: _resource_record_pre_action 09:30:11 INFO - Running main action method: install 09:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:11 INFO - Reading from file tmpfile_stdout 09:30:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:11 INFO - Reading from file tmpfile_stdout 09:30:11 INFO - Output received: 09:30:11 INFO - Usage: mozinstall [options] installer 09:30:11 INFO - Options: 09:30:11 INFO - -h, --help show this help message and exit 09:30:11 INFO - -d DEST, --destination=DEST 09:30:11 INFO - Directory to install application into. [default: 09:30:11 INFO - "/builds/slave/test"] 09:30:11 INFO - --app=APP Application being installed. [default: firefox] 09:30:11 INFO - mkdir: /builds/slave/test/build/application 09:30:11 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'] 09:30:11 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 09:30:45 INFO - Reading from file tmpfile_stdout 09:30:45 INFO - Output received: 09:30:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:45 INFO - Running post-action listener: _resource_record_post_action 09:30:45 INFO - [mozharness: 2016-10-29 16:30:45.635312Z] Finished install step (success) 09:30:45 INFO - [mozharness: 2016-10-29 16:30:45.635465Z] Running run-tests step. 09:30:45 INFO - Running pre-action listener: _resource_record_pre_action 09:30:45 INFO - Running main action method: run_tests 09:30:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:45 INFO - 2016-10-29 09:30:45.650 screenresolution[1636:7442] starting screenresolution argv=screenresolution get 09:30:45 INFO - 2016-10-29 09:30:45.652 screenresolution[1636:7442] Display 0: 1600x1200x32@60 09:30:45 INFO - 2016-10-29 09:30:45.658 screenresolution[1637:7444] starting screenresolution argv=screenresolution list 09:30:45 INFO - Available Modes on Display 0 09:30:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:45 INFO - Intel Iris: 09:30:45 INFO - Chipset Model: Intel Iris 09:30:45 INFO - Type: GPU 09:30:45 INFO - Bus: Built-In 09:30:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:45 INFO - Vendor: Intel (0x8086) 09:30:45 INFO - Device ID: 0x0a2e 09:30:45 INFO - Revision ID: 0x0009 09:30:45 INFO - Displays: 09:30:45 INFO - Display: 09:30:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:45 INFO - Display Serial Number: 1600x1200 60 09:30:45 INFO - Main Display: Yes 09:30:45 INFO - Mirror: Off 09:30:45 INFO - Online: Yes 09:30:45 INFO - Rotation: Supported 09:30:45 INFO - Return code: 0 09:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:45 INFO - Python 2.7.3 09:30:45 INFO - Return code: 0 09:30:45 INFO - grabbing minidump binary from tooltool 09:30:45 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')]} 09:30:45 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 0x103235d30>, '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 0x10305bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10322db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:45 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 09:30:45 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 09:30:45 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 09:30:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:46 INFO - Return code: 0 09:30:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:46 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/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 09:30:46 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/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 09:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pwYSTJpn4S/Render', 09:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:46 INFO - 'HOME': '/Users/cltbld', 09:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:46 INFO - 'LOGNAME': 'cltbld', 09:30:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:46 INFO - 'MOZ_NO_REMOTE': '1', 09:30:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:46 INFO - 'NO_EM_RESTART': '1', 09:30:46 INFO - 'PAGER': '/bin/cat', 09:30:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:46 INFO - 'PWD': '/builds/slave/test', 09:30:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:46 INFO - 'SHELL': '/bin/bash', 09:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xfCYr4kkHd/Listeners', 09:30:46 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:46 INFO - 'USER': 'cltbld', 09:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:46 INFO - 'XPC_FLAGS': '0x0', 09:30:46 INFO - 'XPC_SERVICE_NAME': '0', 09:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:46 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/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 09:30:46 INFO - mozversion application_buildid: 20161029063802 09:30:46 INFO - mozversion application_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 09:30:46 INFO - mozversion application_display_name: Nightly 09:30:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:46 INFO - mozversion application_name: Firefox 09:30:46 INFO - mozversion application_remotingname: firefox 09:30:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:30:46 INFO - mozversion application_vendor: Mozilla 09:30:46 INFO - mozversion application_version: 52.0a1 09:30:46 INFO - mozversion platform_buildid: 20161029063802 09:30:46 INFO - mozversion platform_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 09:30:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:30:46 INFO - mozversion platform_version: 52.0a1 09:30:46 INFO - using testdate: 1477758646 09:30:46 INFO - actual date: 1477758646 09:30:46 INFO - starting webserver on 'localhost:49272' 09:30:46 INFO - SUITE-START | Running 2 tests 09:30:46 INFO - TEST-START | dromaeo_css 09:30:46 INFO - operating with platform_type : mac_ 09:30:46 INFO - Initialising browser for dromaeo_css test... 09:30:46 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmp1WjhAB/profile http://localhost:49272/getInfo.html) 09:30:48 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:30:48 INFO - PROCESS | 1647 | colorDepth:24 09:30:48 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:30:48 INFO - PROCESS | 1647 | __metrics 09:30:49 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:49 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:49 INFO - TEST-INFO | 1647: exit 0 09:30:49 INFO - Browser initialized. 09:30:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:49 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmp1WjhAB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:01 INFO - PROCESS | 1652 | RSS: Main: 232747008 09:31:01 INFO - PROCESS | 1652 | 09:31:53 INFO - PROCESS | 1652 | 1477758713565 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] 09:34:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:34:08 INFO - PROCESS | 1652 | RSS: Main: 380366848 09:34:08 INFO - PROCESS | 1652 | 09:37:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:37:11 INFO - PROCESS | 1652 | RSS: Main: 370626560 09:37:11 INFO - PROCESS | 1652 | 09:40:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:40:14 INFO - PROCESS | 1652 | RSS: Main: 363208704 09:40:14 INFO - PROCESS | 1652 | 09:43:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:43:17 INFO - PROCESS | 1652 | RSS: Main: 342265856 09:43:17 INFO - PROCESS | 1652 | 09:46:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:46:21 INFO - PROCESS | 1652 | RSS: Main: 333672448 09:46:21 INFO - PROCESS | 1652 | 09:49:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:49:25 INFO - PROCESS | 1652 | RSS: Main: 414187520 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | __start_tp_report 09:49:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:49:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:49:25 INFO - PROCESS | 1652 | |i|pagename|runs| 09:49:25 INFO - PROCESS | 1652 | |0;dojo.html;3125.1405863387567;3290.2432440538673;3294.275259442923;3316.6351701312865;3354.681305275998;5469.480399362054;5569.916451253234;5570.275864137661;5658.490735833774;5677.744501497432;5588.413216612258;5596.720163991794;5682.801101961421;5690.800821971232;5707.486326230638;5060.4686507916695;5062;5067.49325067494;5089.516495932899;5164.92252616211;5131.820386286481;5257.947420525784;5259.947400525984;5263.315768950031;5322;5232.6598771080135;5236.685798852062;5241.161414173714;5263.657862238962;5332.280142180814;5035.320231768701;5122.564582010534;5131.1277082895995;5133.922991155136;5168.121419358718;5119.283300337956;5154.5103215194495;5166.844994650167;5197.8960420791755;5211.218317252405;5617.719114044281;5624.21261023457;5627.521660658849;5643.887122257573;5663.207150998864;5984.431479009487;6007.609505382136;6010.519158467312;6145.600535965193;6183.752649893999;6104.337893874325;6117.340260877758;6148.6618235997;6157.692115394258;6159.568830181889;5636.04187288162;5677.148427735872;5698.629589076697;5702.401247868997;5757.8560535986935;6307.716152773136;6319.05214217871;6332.303446620868;6349.714262858182;6375.394337537927;5832.737526811304;6067.908981365283;6074.446220598321;6078.118672792509;6124.571280010446;5997.970010150008;6118.131909153847;6129.450483694044;6133.6319820810895;6143.385661433821;6124.350421260821;6149.877002459926;6152.2924863640355;6154.569180157346;6163.414475624809;6148.862460444832;6162.506999440034;6171.691415429301;6187.907181392283;6196.721147548371;4786.210275304606;4823.493533178966;4844.903101938012;4869.074875773592;4895.877603059952;4278.294081476522;4405.757683327448;4432.667549933703;4439.977800110979;4443.911121777546;5580.469855363734;5661.150827375926;5685.914711279334;5703.942960570341;5709.885802283931;6149.7847575334645;6213.409726076015;6222;6248.562600618004;6248.9062664060075;5481.314835645544;5642.4075472075665;5659.405762394889;5666.801661941812;5679.062954612526;5277.182036784305;5277.815276465305;5311.654742441729;5331.386890507563;5332.760025798771;6229.2524897012645;6240.687965601702;6272.655003974825;6294.590851594632;6307.96846015767;6208.50331973432;6253.843653908689;6254.24949006104;6255.030470277115;6282.623042617458;6208.441240288395;6259.405356491126;6261.5616906816085;6285.779997700059;6311.084892690626;5020.723860187724;6018.458338749533;6065.5754097212775;6138.940218748475;6140.903050468011;6156.661383623944;6179.227596550431;6191.566590338633;6201.534884883562;6204.689765511616;5796.710164491843;5907.009352764686;5964.433378829004;5990;6009.158717779602;5914.408559144051;5925.348211696796;5953.91069133972;5968.97015514937;5989.221401217814;5116.872078198075;5233.973830130977;5823.708814559171;5883.588148829541;5891.3224979127945;5949.286085669747;5992.280926288873;5992.730327135202;5998.310194327533;6002.909956350571;5670.177824215548;5735.967525845388;5738.397468265772;5772.884542309214;5816.3892791258 09:49:25 INFO - PROCESS | 1652 | |1;ext.html;477.756344264425;527.9809966764146;538.7656369479276;545.4409230538698;573.84084150017;25043;40817.59182408175;42118.157636847245;42183.57816421835;43682.563174368246;42706.7864660677;43105.568944310544;43175.3523697144;43205.35191972115;43405.91485212871;9438.622455101788;9567.665131720356;9587.376820506679;9608.567614457366;9613.855792163124;9420.434773913554;9539.379940303892;9539.666111686092;9546.904530954705;9547.474888881108;9476.384035037734;9549.952250238774;9553.426794392324;9571.18644915183;9572.32993690442;9463.384879982814;9516.286278529138;9518;9540.570674319602;9552.140307372336;9398;9466.148046675764;9468.668596599086;9483.715488535356;9492.288078394147;40133.99665008398;40317;40400.1919961602;40555.18889622221;40567.18865622671;3467.583889933199;3519.9120021999397;3553.875614353485;3797.677197438221;3950.280777322931;23104;28671.56992645112;28900.710992890014;29357.85321073402;29912.80348786046;3170.2074481379655;3183.6338821035647;3187.9043628691174;3207.1661368044242;3275.8853440129483;453222;461595;462078.6896065532;462945.6852715749;471895;56664.71667641718;57052.58316418922;57117.42882571204;57156.71421642865;57170.42829571734;34188.316233675185;34959.12602184965;61618.69190654018;61892.69053654703;62010.0698489523;15276;15713.68572628541;15758;15764.290606922716;15798.447054353042;9970.950145249373;12258.957988570894;12314.630561083182;12319.260844349368;12362.567310144103;18955.14701838393;18996.480281577442;19017.714734278998;19051.428457146307;19081.2367505301;6756.797296081126;8086.474379165335;8215.178482151738;8266.917330826735;8341.374396920255;843.8354520868372;975.23444096384;981.5484876956538;1025.600015993763;1050.0234781652982;28550.42899142047;28973.696183671364;29938.850305748332;30819.69180308168;31153.68846311508;862.9395942283979;1070.5824728355947;1077.876044254905;1080.5029686344212;1151.5451396698425;14490.927545362416;14662.780058299135;14707.41170353174;15082.321295541728;15198.696026079417;637585;641826;642201;642292;643498;42330;55562.44437555573;56056;56106;56260.71869640626;1270.0538099116034;1312.8293321868096;1317.960461186166;1367.1250399744172;1435.8564143585768;25017.74982250154;28191.71808281891;29823.850880746326;29889.850550747105;30789.768409264274;48248.793780154374;48835.755821220664;49012.098790120705;49061.773455662464;49149.2627610579;504278;508510;509809;513043;625247;38308.23383532369;39327;39824.80525584237;40104.39843402293;40183.19633607253;36739;45040;45107;45373;46033.84915377142;37497.25005499929;38091.80954095212;38418.80790596029;38628.420573690855;39313.606863930996;29176.7082329174;37547.62452375441;37787;38287;40537;36264.456033159;36631.16076970826;37287.627123728416;37460.250794984495;37509.43735844019;26261.645674321673;31490;33949.981500555026;33957.66042339545;34306.79926202571;24646.630300545865;24743.752562474147;24852.37869053288;25426.618600721373;25451.36371590725 09:49:25 INFO - PROCESS | 1652 | |2;jquery.html;342.5493036988352;370.26317627920446;370.6978812268001;378.36434789553556;397.05698964958225;15029.774553381694;15378.154201518912;15757.84242157578;19859.503512412197;20951.16195352184;18029.90985045073;20148.697769533468;20239.898800506013;20742.170313187453;20926.895365523153;5602.65536271294;5945.227120474332;6254.155688981975;6261.27995280544;6284.5600807943465;5920.319163296212;6172;6199.194104766397;6229.567199544085;6243.937560624404;5871.1193321001765;6107.297660769002;6206.7827626033095;6210.813675589717;6213.658248796314;6095.939040609582;6110.602810817305;6131.417515336036;6140.324564297924;6140.754369825202;5051.419086804991;5271.0248604008;5277.920831187535;5309.495597918191;5320.866978325573;26548.73451265482;26711;26758.464830703277;26787.196384108312;27142;2337.6376661617305;2442.3283597010786;2499.787518060967;2520.3195137312823;3085.475469170224;13313.534026309033;15345.46290879814;15705.293261803246;16209;16250.75623865643;1748;1801.9639607207782;1805.4583624912473;1880.990595047016;1924.980750192494;102055;106708.46645766721;114280;116870;119211;10204.642837500653;10594.046535811814;10668.946655266674;10987.725306867393;11190.94404527972;13645.953107033825;13649.249291289074;13681.521146760015;13685.178889266477;13703.588892333246;8309.418340716093;8563.314934805201;8589.65641374338;8724.302055835518;8748.78128046804;7032.859342813115;8547.188017138362;8550.700725474579;8576.44253123545;8747.387682862138;5418.383052485253;5502.339719233718;5694.288213973254;6597.241317248482;6766.594004359656;1815.8547316214672;1862.6647203503499;1865.4403678896276;1866.7106598477258;1871.709884967832;870.7866572689605;908.1281969309405;933.4772527384687;1014.8782146142363;1045.6235755128148;20985.895070524854;21993.67009494859;22461.4384640382;22581.548369032527;22592.75739834292;969.0842154164388;974.7758015656439;994.3337963564429;1015.8730158730159;1039.2257767962917;8910.153535414254;9244.90755092454;11310.773784524263;11826.763464730657;11938.64184074479;499924;504817;563100;568231;569702;89147.55426222828;116029;118466.40766796283;120636.39681801535;121784.39107804405;374.51500307102367;436.03853503025755;465.6763549333249;467.0006186760336;467.5978658353813;9689.885663148829;9889.703308900744;10235.925227850968;11457.541698332307;11507.539698411969;36486.817565912;50072.748181295756;52181.7390913043;56485.71757141188;56563;62658.68670656617;72579.91130132947;81815.59092204501;82068;82246;5565.8051968182535;5673.687947162945;7975.96612863716;7997.880031799411;8324.292435143081;10364.740881478023;12235.816462753244;12992.480300787862;13189.80215296752;13290.20258784476;9990.901000890042;10762.139028877515;10789.514471848628;11342.886571134182;11690.357030363071;5071.594272458268;8232.300254478436;10158;11353.015613634607;11602.477888495207;5753.568482363923;8804.60379282921;9931.950340248253;11484.966353028267;11742.530298787953;7703.614819259126;7963.840723185388;8239.217274358927;8407.915920840713;8429.283510901641;9478.478683672189;11130;11187.440627968732;11553.480093395649;11561.710957226136 09:49:25 INFO - PROCESS | 1652 | |3;mootools.html;1369.8082268482422;1438;1502.241368109105;1702.2765324736984;1945.4747218251082;7036.40190583801;7412;7718.343940765035;8317.043539992903;8321;8129.796755081126;8206.425550211488;8208.507489550617;8222.465539739927;8245.96925384327;2234.3520379090087;2260.4122928038664;2262.4457008032973;2263.0155882191298;2263.2418139923147;2249.460129568908;2292.805111565519;2310.653401989698;2313.1672597864754;2314.8032417244553;1841.530409745513;2223.288547664748;2257.604919139152;2266.3767463947383;2269.2624896908487;2202.3503066595395;2220.5891909996703;2259.9322020339414;2269.841111122222;2270.489139943508;2270.1600407849037;2300.3903965449167;2303.67748515208;2304.135949019118;2306.411864974421;8152.592370381457;8227.712030078977;8230.506169629782;8252.917470825336;8311.085780564134;5007.974960125212;5204.791808327663;5206.76569554371;5214;5214.217867319894;3321.4187517184396;3521.841517131735;3525.7355698322726;3586.211033572636;3600.6039335673054;962.8507581324906;1000.848176420694;1051.8579991701176;1062.8033813744482;1071.7159952612565;10582.153427725763;10626;10709.839352409721;10715.946420267926;10741.785164296749;4141.958580414218;4295.22685916538;4387.4954380246045;4389.956100439019;4391.978040109779;8346.8330633387;8365.498070115813;8379.37154713399;8382.07797142314;8390.538520381439;4695.624350051988;5024.547790698782;5026.271190677405;5032.521910418504;5032.943081952758;4226.978865105655;4252.978735106305;4274.658027357804;4379.233634114081;4563.657725670588;3234.9676503235137;3378.847951842173;3553.804540750232;3564.875229367012;3587.336342776595;1020.7601213714771;1103.2773533335678;1110.189561620022;1111.9054880335261;1113.2151832957745;825.8232019372371;848.3382961290204;892.5849479991834;898.7842917699705;930.9115634014757;5292.52367286946;5407.324084489439;5674.148877668383;5683.857903552362;5693.316801983706;947.5688561704408;980.2794945714894;992.1318846009741;998.8601595776597;1003.508280942333;3619.6561326673923;3664.2488289900507;3671.6879065279218;3771.641694039062;3784.7073046449764;8532.829343413097;8997.325200609981;9497.43015419077;9827.459489727991;9947.45289009097;9355.625774969061;9753.609855605697;9887;9888.357256778287;9897.455639939872;6494;7561.7731468056045;7676.961615192;7770.844583108306;7784.987951566261;4939.777710002986;5377.704226267437;5466.442064011897;5484.7806087757635;6016.759329626766;8871.290296776113;9228.123328283802;9375.531223438937;9380.155785979021;9398.06019398082;7191.820204494929;9263.444193348421;9323.860142097737;9453.385529940531;9474.289428292768;6571.605703657796;6779.949107888286;7025.156981162293;7192.640367981685;7194.244604316681;7620.695172193272;7826.491278066907;7898.644560994884;7927.603619819101;7946.960265198637;6592.868142637025;6677.232118306457;6993.825154371181;7138.964305178441;7261.346478816932;6857.485688573277;6916.273791251846;6930;6962.547434416747;6999.8950015750825;5910.320313164041;6373.107764912823;6535.44448721864;6546.705398256994;6645.202575690948;4629.722216666876;4673.7896794645;4778.450478195052;4796.088743138793;4928.697772492367;4737.692050016738;4851.442084160367;4886.92669609949;4894.828680996148;4919.606431485401 09:49:25 INFO - PROCESS | 1652 | |4;prototype.html;300.9443252998194;301.58532018474597;311.54514408962905;316.94928811390184;319.21154747772994;4152.979235103824;4419.58013988671;5273.2617433559335;5301.072312345339;5319.494648008433;4143.73065750726;4219;4946.0602485527725;4964.7765850536725;5271.65734227275;3872;3957.9010524736896;4042.443659191203;4044.352903535435;4105.815238314268;3683.3922402803505;4027.637512623863;4054.128362402083;4070.7354021988476;4107.691923105779;3939.330313846653;3974.8671628585857;4012.638862502374;4045.797710114497;4065.227606754722;3445.5693038370077;3527.9945215613425;3840.1935593525395;4035.3366777926426;4065.5934406559404;3522.5420695309704;3535.681788639009;3537.663921927413;3540.3804334241663;3541.3625547401457;4577.816887324503;4818.819389249621;5103.642745007851;5130.230465430178;5141.562967147797;4380.364847097185;4602.079584083163;4626.699264547794;4632.540749663879;4644.2816158021415;5451.236826844205;5452.836414907559;5469;5471.042567550663;5503.229547863302;3280.311134661724;3873.1479074603544;3882.378819388884;3888.863889763845;3904.297226499229;4817.927731084036;5208.791648334024;5220.797258207142;5346.866328341823;5818.41815818419;3722.571904230994;3807.5430948286385;3811.4282857571034;3814.5613254475534;3839.827207775657;3248.642649308574;3561.2343346180696;3860.942085868714;3866.38137897935;3900.180961998012;3447.7069449096607;3806.790626515513;4296.324433470949;4461.933071003938;4543.795529201194;5398.001369746609;5439.918401223985;5556.722163891789;5585.469380408855;5613.158026296007;1235.2403271987662;1303.4330066421137;1320.8811206991418;1325.4300650720284;1332.433715670836;695.3637421759134;840.9285210757032;861.0657436681146;876.412803421709;898.725888603969;5253.579713622901;5271.6309858310315;5291.947080529146;5308.309919710413;5312.123499622519;5279.234510995884;5313.44208858072;5684.516816070598;5736.397678243808;5752.398081534756;4660.555227879368;4745;5105.923411148836;5164.7934082636275;5267.868303292448;2345.0619752099296;2918.795684302121;2929.428761391508;2933.530635098374;2935.9559606605917;4639.932815452465;5251.291075704731;5588.133839254922;5703.914441283384;5747.971260143672;4530.705504142286;5905.291365036137;5975.521958243417;5992.490638295793;6005.759769609254;4862.513748625109;4866.270059491105;5888.440598143169;6000.369961154103;6024.879502409927;4700.92948605778;5731.910936921971;5751.4536119068625;5761.855953601026;5823.388544202798;5391.840754237857;5635.8591035224445;5646.447227012604;5662.85842853932;5699.29021293602;4735.289706544046;5196.506331898464;5496.80761173357;5828.737706803119;5932.614380065282;3720.2656047804303;3844.6928044465017;3861.208452267222;3878.166194268246;3893.571707112272;5747.827565173052;5757.5393968481585;5778.971105144616;5800.970995144998;5821.621594596338;4467.994701192336;4868.853934381974;5029.497050294941;5634.408387119457;5651;5479.726013699219;5584.916226256691;5626.774929002794;5630.943690563206;5640.830775076754;4037.6971727119994;4647.86056418308;5238.485526947163;5504.944950550604;5533.087040638249;4727.747147006115;4904.376158755049;5061.569766569883;5169.379674439091;5480.479354461319;4600.539946005372;5103.851633382459;5241.528262456397;5349.4650534946195;5509.834704958857 09:49:25 INFO - PROCESS | 1652 | |5;yui.html;740.637087826965;774.8992630957974;791.5725508225557;814.1936447109761;869.9391042627019;14482.420703171874;15070.246487675628;15120.092794432341;15177.241137943087;15225.238738063093;13619.863801361984;13751.449942002333;13777.51778687746;13791.793123103162;14235.71528569428;682.778097118436;712.8859382498802;717.1107826295382;871.4553404122423;915.2632131134434;882.867569864519;895.928325733943;898.6989358564889;899.1952202778509;901.062894589626;961.2022021721954;962.0764066496137;964.3924327673556;966.183574879226;967.9951600242024;733.0946281342538;762.6949220311864;767.4627760567569;958.420155805738;960.0063933828488;567.5998421113105;568.5309619563876;689.4174422612884;720.4056653261081;720.8125887269296;15079.170645614488;15086;15087.39650413982;15090.39638414462;15119.773203401957;11235.325880447117;13444.32778361078;13469.865301347058;13615.795763063563;13734.93132534331;10804.351738895613;12337.691557711129;12354.876451235552;12388.504459821595;12872.678183045406;3864.8260828262796;4466.955330446686;4549.45406551216;4549.772511374418;4595.379623750784;86026;86373.70439443413;87658.12341876564;89245;90467.09532904653;10728.570857165625;10765.084967777826;10817.350958942488;10843.83734243987;11077.169212309109;20811.375658730263;20815.687764683542;21023.8948805255;21417.89291053566;21419.785802141778;7607.467477276648;7658.693652253847;7698.230176982257;7700.422468314899;7910.248526389983;4276.935845962313;4389.670774691911;4393.0994146199955;4398.956010439919;4456.75487848165;3346.113279980807;3354.07762865214;3382.0530251529613;3452.5339079224486;3454.4300190468794;628.1080865171467;632.867097909435;636.3541005879036;649.1236830279086;651.0852252585125;593.7654626422625;598.1446531460053;620.7485968182893;638.6040654793965;642.0754114075716;8541.530215838064;8834.602442890086;8849;8882.644694212158;9002.324825638103;602.7877688881814;616.5745635511482;624.622103627308;632.0456111272013;648.4941745438565;3359.546461227752;4269.402283680287;4305.784710764449;4427.912513018921;4463.642908567372;79789.20210797933;93799.53100234455;111923.44038279756;115458;121568;98109;102483.4875825631;103548;104767.95232047734;105899;83753.58123209345;83857.58071209605;86371.56814215888;89625;94613;8173.591320434073;8442.731783286226;8510.48937063778;8760.25537829266;8835.249003834746;52657.736711316196;52755.417337479936;53533.464665354404;54513.18230226471;55006.44993550173;61681;66421.33578664152;66922.66538667471;75210.24789752033;78141;12262.632121036384;12575.056870734914;12698.238105713686;12730.490780369051;12825.551105711254;14242.940349866858;14320.57038288853;14710.705785884436;14816.40734370613;14869.107853528823;13203.481599615783;13212;13292.20246785196;13830.23933683657;14076.366563504871;13229.669258268621;13415.329233538088;13439.798403023768;13553.796693049415;13833.792493112813;12777.361131943551;12979.41592628353;13323.533676321282;13455.394507247001;13549.051566390652;9627.326087173831;10913.110370890228;11253.606123785628;11293.943530282297;11358.943205284251;9834.75413114678;11181.32912025281;11202.66392008241;11292.266002709797;11441.313521188755 09:49:25 INFO - PROCESS | 1652 | __end_tp_report 09:49:25 INFO - PROCESS | 1652 | __start_cc_report 09:49:25 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1646 09:49:25 INFO - PROCESS | 1652 | __end_cc_report 09:49:25 INFO - PROCESS | 1652 | __startTimestamp1477759765026__endTimestamp 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:49:25 INFO - PROCESS | 1652 | Number of tests: 6 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5662.25 Median:5796.71 stddev:637.42 (11.0%) stddev-sans-first:607.51 09:49:25 INFO - PROCESS | 1652 | Values: 3125.1 3290.2 3294.3 3316.6 3354.7 5469.5 5569.9 5570.3 5658.5 5677.7 5588.4 5596.7 5682.8 5690.8 5707.5 5060.5 5062.0 5067.5 5089.5 5164.9 5131.8 5257.9 5259.9 5263.3 5322.0 5232.7 5236.7 5241.2 5263.7 5332.3 5035.3 5122.6 5131.1 5133.9 5168.1 5119.3 5154.5 5166.8 5197.9 5211.2 5617.7 5624.2 5627.5 5643.9 5663.2 5984.4 6007.6 6010.5 6145.6 6183.8 6104.3 6117.3 6148.7 6157.7 6159.6 5636.0 5677.1 5698.6 5702.4 5757.9 6307.7 6319.1 6332.3 6349.7 6375.4 5832.7 6067.9 6074.4 6078.1 6124.6 5998.0 6118.1 6129.5 6133.6 6143.4 6124.4 6149.9 6152.3 6154.6 6163.4 6148.9 6162.5 6171.7 6187.9 6196.7 4786.2 4823.5 4844.9 4869.1 4895.9 4278.3 4405.8 4432.7 4440.0 4443.9 5580.5 5661.2 5685.9 5703.9 5709.9 6149.8 6213.4 6222.0 6248.6 6248.9 5481.3 5642.4 5659.4 5666.8 5679.1 5277.2 5277.8 5311.7 5331.4 5332.8 6229.3 6240.7 6272.7 6294.6 6308.0 6208.5 6253.8 6254.2 6255.0 6282.6 6208.4 6259.4 6261.6 6285.8 6311.1 5020.7 6018.5 6065.6 6138.9 6140.9 6156.7 6179.2 6191.6 6201.5 6204.7 5796.7 5907.0 5964.4 5990.0 6009.2 5914.4 5925.3 5953.9 5969.0 5989.2 5116.9 5234.0 5823.7 5883.6 5891.3 5949.3 5992.3 5992.7 5998.3 6002.9 5670.2 5736.0 5738.4 5772.9 5816.4 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:67638.00 Median:40468.60 stddev:147197.96 (363.7%) stddev-sans-first:147524.52 09:49:25 INFO - PROCESS | 1652 | Values: 477.8 528.0 538.8 545.4 573.8 25043.0 40817.6 42118.2 42183.6 43682.6 42706.8 43105.6 43175.4 43205.4 43405.9 9438.6 9567.7 9587.4 9608.6 9613.9 9420.4 9539.4 9539.7 9546.9 9547.5 9476.4 9550.0 9553.4 9571.2 9572.3 9463.4 9516.3 9518.0 9540.6 9552.1 9398.0 9466.1 9468.7 9483.7 9492.3 40134.0 40317.0 40400.2 40555.2 40567.2 3467.6 3519.9 3553.9 3797.7 3950.3 23104.0 28671.6 28900.7 29357.9 29912.8 3170.2 3183.6 3187.9 3207.2 3275.9 453222.0 461595.0 462078.7 462945.7 471895.0 56664.7 57052.6 57117.4 57156.7 57170.4 34188.3 34959.1 61618.7 61892.7 62010.1 15276.0 15713.7 15758.0 15764.3 15798.4 9971.0 12259.0 12314.6 12319.3 12362.6 18955.1 18996.5 19017.7 19051.4 19081.2 6756.8 8086.5 8215.2 8266.9 8341.4 843.8 975.2 981.5 1025.6 1050.0 28550.4 28973.7 29938.9 30819.7 31153.7 862.9 1070.6 1077.9 1080.5 1151.5 14490.9 14662.8 14707.4 15082.3 15198.7 637585.0 641826.0 642201.0 642292.0 643498.0 42330.0 55562.4 56056.0 56106.0 56260.7 1270.1 1312.8 1318.0 1367.1 1435.9 25017.7 28191.7 29823.9 29889.9 30789.8 48248.8 48835.8 49012.1 49061.8 49149.3 504278.0 508510.0 509809.0 513043.0 625247.0 38308.2 39327.0 39824.8 40104.4 40183.2 36739.0 45040.0 45107.0 45373.0 46033.8 37497.3 38091.8 38418.8 38628.4 39313.6 29176.7 37547.6 37787.0 38287.0 40537.0 36264.5 36631.2 37287.6 37460.3 37509.4 26261.6 31490.0 33950.0 33957.7 34306.8 24646.6 24743.8 24852.4 25426.6 25451.4 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:32503.77 Median:372.61 stddev:90554.44 (24303.0%) stddev-sans-first:90776.27 09:49:25 INFO - PROCESS | 1652 | Values: 342.5 370.3 370.7 378.4 397.1 15029.8 15378.2 15757.8 19859.5 20951.2 18029.9 20148.7 20239.9 20742.2 20926.9 5602.7 5945.2 6254.2 6261.3 6284.6 5920.3 6172.0 6199.2 6229.6 6243.9 5871.1 6107.3 6206.8 6210.8 6213.7 6095.9 6110.6 6131.4 6140.3 6140.8 5051.4 5271.0 5277.9 5309.5 5320.9 26548.7 26711.0 26758.5 26787.2 27142.0 2337.6 2442.3 2499.8 2520.3 3085.5 13313.5 15345.5 15705.3 16209.0 16250.8 1748.0 1802.0 1805.5 1881.0 1925.0 102055.0 106708.5 114280.0 116870.0 119211.0 10204.6 10594.0 10668.9 10987.7 11190.9 13646.0 13649.2 13681.5 13685.2 13703.6 8309.4 8563.3 8589.7 8724.3 8748.8 7032.9 8547.2 8550.7 8576.4 8747.4 5418.4 5502.3 5694.3 6597.2 6766.6 1815.9 1862.7 1865.4 1866.7 1871.7 870.8 908.1 933.5 1014.9 1045.6 20985.9 21993.7 22461.4 22581.5 22592.8 969.1 974.8 994.3 1015.9 1039.2 8910.2 9244.9 11310.8 11826.8 11938.6 499924.0 504817.0 563100.0 568231.0 569702.0 89147.6 116029.0 118466.4 120636.4 121784.4 374.5 436.0 465.7 467.0 467.6 9689.9 9889.7 10235.9 11457.5 11507.5 36486.8 50072.7 52181.7 56485.7 56563.0 62658.7 72579.9 81815.6 82068.0 82246.0 5565.8 5673.7 7976.0 7997.9 8324.3 10364.7 12235.8 12992.5 13189.8 13290.2 9990.9 10762.1 10789.5 11342.9 11690.4 5071.6 8232.3 10158.0 11353.0 11602.5 5753.6 8804.6 9932.0 11485.0 11742.5 7703.6 7963.8 8239.2 8407.9 8429.3 9478.5 11130.0 11187.4 11553.5 11561.7 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5278.29 Median:5253.37 stddev:2871.52 (54.7%) stddev-sans-first:2864.55 09:49:25 INFO - PROCESS | 1652 | Values: 1369.8 1438.0 1502.2 1702.3 1945.5 7036.4 7412.0 7718.3 8317.0 8321.0 8129.8 8206.4 8208.5 8222.5 8246.0 2234.4 2260.4 2262.4 2263.0 2263.2 2249.5 2292.8 2310.7 2313.2 2314.8 1841.5 2223.3 2257.6 2266.4 2269.3 2202.4 2220.6 2259.9 2269.8 2270.5 2270.2 2300.4 2303.7 2304.1 2306.4 8152.6 8227.7 8230.5 8252.9 8311.1 5008.0 5204.8 5206.8 5214.0 5214.2 3321.4 3521.8 3525.7 3586.2 3600.6 962.9 1000.8 1051.9 1062.8 1071.7 10582.2 10626.0 10709.8 10715.9 10741.8 4142.0 4295.2 4387.5 4390.0 4392.0 8346.8 8365.5 8379.4 8382.1 8390.5 4695.6 5024.5 5026.3 5032.5 5032.9 4227.0 4253.0 4274.7 4379.2 4563.7 3235.0 3378.8 3553.8 3564.9 3587.3 1020.8 1103.3 1110.2 1111.9 1113.2 825.8 848.3 892.6 898.8 930.9 5292.5 5407.3 5674.1 5683.9 5693.3 947.6 980.3 992.1 998.9 1003.5 3619.7 3664.2 3671.7 3771.6 3784.7 8532.8 8997.3 9497.4 9827.5 9947.5 9355.6 9753.6 9887.0 9888.4 9897.5 6494.0 7561.8 7677.0 7770.8 7785.0 4939.8 5377.7 5466.4 5484.8 6016.8 8871.3 9228.1 9375.5 9380.2 9398.1 7191.8 9263.4 9323.9 9453.4 9474.3 6571.6 6779.9 7025.2 7192.6 7194.2 7620.7 7826.5 7898.6 7927.6 7947.0 6592.9 6677.2 6993.8 7139.0 7261.3 6857.5 6916.3 6930.0 6962.5 6999.9 5910.3 6373.1 6535.4 6546.7 6645.2 4629.7 4673.8 4778.5 4796.1 4928.7 4737.7 4851.4 4886.9 4894.8 4919.6 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4442.77 Median:4886.62 stddev:1369.53 (28.0%) stddev-sans-first:1337.62 09:49:25 INFO - PROCESS | 1652 | Values: 300.9 301.6 311.5 316.9 319.2 4153.0 4419.6 5273.3 5301.1 5319.5 4143.7 4219.0 4946.1 4964.8 5271.7 3872.0 3957.9 4042.4 4044.4 4105.8 3683.4 4027.6 4054.1 4070.7 4107.7 3939.3 3974.9 4012.6 4045.8 4065.2 3445.6 3528.0 3840.2 4035.3 4065.6 3522.5 3535.7 3537.7 3540.4 3541.4 4577.8 4818.8 5103.6 5130.2 5141.6 4380.4 4602.1 4626.7 4632.5 4644.3 5451.2 5452.8 5469.0 5471.0 5503.2 3280.3 3873.1 3882.4 3888.9 3904.3 4817.9 5208.8 5220.8 5346.9 5818.4 3722.6 3807.5 3811.4 3814.6 3839.8 3248.6 3561.2 3860.9 3866.4 3900.2 3447.7 3806.8 4296.3 4461.9 4543.8 5398.0 5439.9 5556.7 5585.5 5613.2 1235.2 1303.4 1320.9 1325.4 1332.4 695.4 840.9 861.1 876.4 898.7 5253.6 5271.6 5291.9 5308.3 5312.1 5279.2 5313.4 5684.5 5736.4 5752.4 4660.6 4745.0 5105.9 5164.8 5267.9 2345.1 2918.8 2929.4 2933.5 2936.0 4639.9 5251.3 5588.1 5703.9 5748.0 4530.7 5905.3 5975.5 5992.5 6005.8 4862.5 4866.3 5888.4 6000.4 6024.9 4700.9 5731.9 5751.5 5761.9 5823.4 5391.8 5635.9 5646.4 5662.9 5699.3 4735.3 5196.5 5496.8 5828.7 5932.6 3720.3 3844.7 3861.2 3878.2 3893.6 5747.8 5757.5 5779.0 5801.0 5821.6 4468.0 4868.9 5029.5 5634.4 5651.0 5479.7 5584.9 5626.8 5630.9 5640.8 4037.7 4647.9 5238.5 5504.9 5533.1 4727.7 4904.4 5061.6 5169.4 5480.5 4600.5 5103.9 5241.5 5349.5 5509.8 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:20698.41 Median:4465.30 stddev:30140.08 (675.0%) stddev-sans-first:30187.37 09:49:25 INFO - PROCESS | 1652 | Values: 740.6 774.9 791.6 814.2 869.9 14482.4 15070.2 15120.1 15177.2 15225.2 13619.9 13751.4 13777.5 13791.8 14235.7 682.8 712.9 717.1 871.5 915.3 882.9 895.9 898.7 899.2 901.1 961.2 962.1 964.4 966.2 968.0 733.1 762.7 767.5 958.4 960.0 567.6 568.5 689.4 720.4 720.8 15079.2 15086.0 15087.4 15090.4 15119.8 11235.3 13444.3 13469.9 13615.8 13734.9 10804.4 12337.7 12354.9 12388.5 12872.7 3864.8 4467.0 4549.5 4549.8 4595.4 86026.0 86373.7 87658.1 89245.0 90467.1 10728.6 10765.1 10817.4 10843.8 11077.2 20811.4 20815.7 21023.9 21417.9 21419.8 7607.5 7658.7 7698.2 7700.4 7910.2 4276.9 4389.7 4393.1 4399.0 4456.8 3346.1 3354.1 3382.1 3452.5 3454.4 628.1 632.9 636.4 649.1 651.1 593.8 598.1 620.7 638.6 642.1 8541.5 8834.6 8849.0 8882.6 9002.3 602.8 616.6 624.6 632.0 648.5 3359.5 4269.4 4305.8 4427.9 4463.6 79789.2 93799.5 111923.4 115458.0 121568.0 98109.0 102483.5 103548.0 104768.0 105899.0 83753.6 83857.6 86371.6 89625.0 94613.0 8173.6 8442.7 8510.5 8760.3 8835.2 52657.7 52755.4 53533.5 54513.2 55006.4 61681.0 66421.3 66922.7 75210.2 78141.0 12262.6 12575.1 12698.2 12730.5 12825.6 14242.9 14320.6 14710.7 14816.4 14869.1 13203.5 13212.0 13292.2 13830.2 14076.4 13229.7 13415.3 13439.8 13553.8 13833.8 12777.4 12979.4 13323.5 13455.4 13549.1 9627.3 10913.1 11253.6 11293.9 11358.9 9834.8 11181.3 11202.7 11292.3 11441.3 09:49:25 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:49:25 INFO - PROCESS | 1652 | 09:49:25 INFO - TEST-INFO | 1652: exit 0 09:49:25 INFO - TEST-OK | dromaeo_css | took 1119407ms 09:49:25 INFO - TEST-START | kraken 09:49:25 INFO - operating with platform_type : mac_ 09:49:25 INFO - Initialising browser for kraken test... 09:49:25 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpaPJlKh/profile http://localhost:49272/getInfo.html) 09:49:27 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:49:27 INFO - PROCESS | 1658 | colorDepth:24 09:49:27 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:49:27 INFO - PROCESS | 1658 | __metrics 09:49:28 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:49:28 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:28 INFO - TEST-INFO | 1658: exit 0 09:49:28 INFO - Browser initialized. 09:49:28 INFO - Running cycle 1/1 for kraken test... 09:49:28 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpaPJlKh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:40 INFO - PROCESS | 1659 | RSS: Main: 241172480 09:49:40 INFO - PROCESS | 1659 | 09:49:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:44 INFO - PROCESS | 1659 | RSS: Main: 292667392 09:49:44 INFO - PROCESS | 1659 | 09:49:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:49:47 INFO - PROCESS | 1659 | RSS: Main: 294629376 09:49:47 INFO - PROCESS | 1659 | 09:49:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:49:51 INFO - PROCESS | 1659 | RSS: Main: 382287872 09:49:51 INFO - PROCESS | 1659 | 09:49:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:49:54 INFO - PROCESS | 1659 | RSS: Main: 284852224 09:49:54 INFO - PROCESS | 1659 | 09:49:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:57 INFO - PROCESS | 1659 | RSS: Main: 290226176 09:49:57 INFO - PROCESS | 1659 | 09:49:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:00 INFO - PROCESS | 1659 | RSS: Main: 327634944 09:50:00 INFO - PROCESS | 1659 | 09:50:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:04 INFO - PROCESS | 1659 | RSS: Main: 328310784 09:50:04 INFO - PROCESS | 1659 | 09:50:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:50:07 INFO - PROCESS | 1659 | RSS: Main: 327729152 09:50:07 INFO - PROCESS | 1659 | 09:50:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:09 INFO - PROCESS | 1659 | RSS: Main: 302514176 09:50:09 INFO - PROCESS | 1659 | 09:50:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:12 INFO - PROCESS | 1659 | RSS: Main: 306188288 09:50:12 INFO - PROCESS | 1659 | 09:50:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:14 INFO - PROCESS | 1659 | RSS: Main: 308994048 09:50:14 INFO - PROCESS | 1659 | 09:50:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:18 INFO - PROCESS | 1659 | RSS: Main: 318640128 09:50:18 INFO - PROCESS | 1659 | 09:50:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:22 INFO - PROCESS | 1659 | RSS: Main: 318517248 09:50:22 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:50:24 INFO - PROCESS | 1659 | RSS: Main: 362831872 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | __start_tp_report 09:50:24 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:50:24 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:50:24 INFO - PROCESS | 1659 | |i|pagename|runs| 09:50:24 INFO - PROCESS | 1659 | |0;ai-astar;113;114;114;114;113;114;114;115;112;113 09:50:24 INFO - PROCESS | 1659 | |1;audio-beat-detection;175;141;141;152;112;113;113;114;146;120 09:50:24 INFO - PROCESS | 1659 | |2;audio-dft;181;185;185;184;185;183;180;183;184;183 09:50:24 INFO - PROCESS | 1659 | |3;audio-fft;79;59;59;88;62;58;59;59;84;57 09:50:24 INFO - PROCESS | 1659 | |4;audio-oscillator;86;70;95;91;98;101;101;67;68;90 09:50:24 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;88;85;88;85;87;84;87;85;87;84 09:50:24 INFO - PROCESS | 1659 | |6;imaging-darkroom;93;93;94;94;93;92;110;152;149;92 09:50:24 INFO - PROCESS | 1659 | |7;imaging-desaturate;83;84;79;82;84;90;137;131;85;84 09:50:24 INFO - PROCESS | 1659 | |8;json-parse-financial;57;55;57;57;55;57;55;55;54;56 09:50:24 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;68;65;69;67;72;75;69;69;67;68 09:50:24 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;91;87;85;87;86;98;97;89;85;86 09:50:24 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;148;147;148;149;132;175;156;157;239;144 09:50:24 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;172;171;172;180;172;170;168;173;171;170 09:50:24 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;68;68;68;66;66;67;66;68;65;62 09:50:24 INFO - PROCESS | 1659 | __end_tp_report 09:50:24 INFO - PROCESS | 1659 | __start_cc_report 09:50:24 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2019 09:50:24 INFO - PROCESS | 1659 | __end_cc_report 09:50:24 INFO - PROCESS | 1659 | __startTimestamp1477759824258__endTimestamp 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:50:24 INFO - PROCESS | 1659 | Number of tests: 14 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:0.84 (0.7%) stddev-sans-first:0.87 09:50:24 INFO - PROCESS | 1659 | Values: 113.0 114.0 114.0 114.0 113.0 114.0 114.0 115.0 112.0 113.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:141.00 stddev:21.58 (15.3%) stddev-sans-first:16.60 09:50:24 INFO - PROCESS | 1659 | Values: 175.0 141.0 141.0 152.0 112.0 113.0 113.0 114.0 146.0 120.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.70 (0.9%) stddev-sans-first:1.59 09:50:24 INFO - PROCESS | 1659 | Values: 181.0 185.0 185.0 184.0 185.0 183.0 180.0 183.0 184.0 183.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.17 (20.1%) stddev-sans-first:12.02 09:50:24 INFO - PROCESS | 1659 | Values: 79.0 59.0 59.0 88.0 62.0 58.0 59.0 59.0 84.0 57.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:88.00 stddev:13.55 (15.4%) stddev-sans-first:14.37 09:50:24 INFO - PROCESS | 1659 | Values: 86.0 70.0 95.0 91.0 98.0 101.0 101.0 67.0 68.0 90.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.56 (1.8%) stddev-sans-first:1.48 09:50:24 INFO - PROCESS | 1659 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 87.0 85.0 87.0 84.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:93.00 stddev:23.96 (25.8%) stddev-sans-first:24.93 09:50:24 INFO - PROCESS | 1659 | Values: 93.0 93.0 94.0 94.0 93.0 92.0 110.0 152.0 149.0 92.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:84.00 stddev:21.36 (25.4%) stddev-sans-first:22.28 09:50:24 INFO - PROCESS | 1659 | Values: 83.0 84.0 79.0 82.0 84.0 90.0 137.0 131.0 85.0 84.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.50 stddev:1.14 (2.0%) stddev-sans-first:1.12 09:50:24 INFO - PROCESS | 1659 | Values: 57.0 55.0 57.0 57.0 55.0 57.0 55.0 55.0 54.0 56.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:2.81 (4.1%) stddev-sans-first:2.96 09:50:24 INFO - PROCESS | 1659 | Values: 68.0 65.0 69.0 67.0 72.0 75.0 69.0 69.0 67.0 68.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:88.00 stddev:4.79 (5.4%) stddev-sans-first:5.04 09:50:24 INFO - PROCESS | 1659 | Values: 91.0 87.0 85.0 87.0 86.0 98.0 97.0 89.0 85.0 86.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:159.50 Median:152.50 stddev:30.01 (19.7%) stddev-sans-first:31.54 09:50:24 INFO - PROCESS | 1659 | Values: 148.0 147.0 148.0 149.0 132.0 175.0 156.0 157.0 239.0 144.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:3.18 (1.8%) stddev-sans-first:3.37 09:50:24 INFO - PROCESS | 1659 | Values: 172.0 171.0 172.0 180.0 172.0 170.0 168.0 173.0 171.0 170.0 09:50:24 INFO - PROCESS | 1659 | 09:50:24 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.50 stddev:1.90 (2.8%) stddev-sans-first:1.92 09:50:24 INFO - PROCESS | 1659 | Values: 68.0 68.0 68.0 66.0 66.0 67.0 66.0 68.0 65.0 62.0 09:50:24 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:50:24 INFO - PROCESS | 1659 | 09:50:25 INFO - TEST-INFO | 1659: exit 0 09:50:25 INFO - TEST-OK | kraken | took 59534ms 09:50:25 INFO - SUITE-END | took 1178s 09:50:25 INFO - Completed test suite (00:19:39) 09:50:25 INFO - javascript benchmark 09:50:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.1405863387567, 3290.2432440538673, 3294.275259442923, 3316.6351701312865, 3354.681305275998, 5469.480399362054, 5569.916451253234, 5570.275864137661, 5658.490735833774, 5677.744501497432, 5588.413216612258, 5596.720163991794, 5682.801101961421, 5690.800821971232, 5707.486326230638, 5060.4686507916695, 5062.0, 5067.49325067494, 5089.516495932899, 5164.92252616211, 5131.820386286481, 5257.947420525784, 5259.947400525984, 5263.315768950031, 5322.0, 5232.6598771080135, 5236.685798852062, 5241.161414173714, 5263.657862238962, 5332.280142180814, 5035.320231768701, 5122.564582010534, 5131.1277082895995, 5133.922991155136, 5168.121419358718, 5119.283300337956, 5154.5103215194495, 5166.844994650167, 5197.8960420791755, 5211.218317252405, 5617.719114044281, 5624.21261023457, 5627.521660658849, 5643.887122257573, 5663.207150998864, 5984.431479009487, 6007.609505382136, 6010.519158467312, 6145.600535965193, 6183.752649893999, 6104.337893874325, 6117.340260877758, 6148.6618235997, 6157.692115394258, 6159.568830181889, 5636.04187288162, 5677.148427735872, 5698.629589076697, 5702.401247868997, 5757.8560535986935, 6307.716152773136, 6319.05214217871, 6332.303446620868, 6349.714262858182, 6375.394337537927, 5832.737526811304, 6067.908981365283, 6074.446220598321, 6078.118672792509, 6124.571280010446, 5997.970010150008, 6118.131909153847, 6129.450483694044, 6133.6319820810895, 6143.385661433821, 6124.350421260821, 6149.877002459926, 6152.2924863640355, 6154.569180157346, 6163.414475624809, 6148.862460444832, 6162.506999440034, 6171.691415429301, 6187.907181392283, 6196.721147548371, 4786.210275304606, 4823.493533178966, 4844.903101938012, 4869.074875773592, 4895.877603059952, 4278.294081476522, 4405.757683327448, 4432.667549933703, 4439.977800110979, 4443.911121777546, 5580.469855363734, 5661.150827375926, 5685.914711279334, 5703.942960570341, 5709.885802283931, 6149.7847575334645, 6213.409726076015, 6222.0, 6248.562600618004, 6248.9062664060075, 5481.314835645544, 5642.4075472075665, 5659.405762394889, 5666.801661941812, 5679.062954612526, 5277.182036784305, 5277.815276465305, 5311.654742441729, 5331.386890507563, 5332.760025798771, 6229.2524897012645, 6240.687965601702, 6272.655003974825, 6294.590851594632, 6307.96846015767, 6208.50331973432, 6253.843653908689, 6254.24949006104, 6255.030470277115, 6282.623042617458, 6208.441240288395, 6259.405356491126, 6261.5616906816085, 6285.779997700059, 6311.084892690626, 5020.723860187724, 6018.458338749533, 6065.5754097212775, 6138.940218748475, 6140.903050468011, 6156.661383623944, 6179.227596550431, 6191.566590338633, 6201.534884883562, 6204.689765511616, 5796.710164491843, 5907.009352764686, 5964.433378829004, 5990.0, 6009.158717779602, 5914.408559144051, 5925.348211696796, 5953.91069133972, 5968.97015514937, 5989.221401217814, 5116.872078198075, 5233.973830130977, 5823.708814559171, 5883.588148829541, 5891.3224979127945, 5949.286085669747, 5992.280926288873, 5992.730327135202, 5998.310194327533, 6002.909956350571, 5670.177824215548, 5735.967525845388, 5738.397468265772, 5772.884542309214, 5816.3892791258], "value": 5620.801210584266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.756344264425, 527.9809966764146, 538.7656369479276, 545.4409230538698, 573.84084150017, 25043.0, 40817.59182408175, 42118.157636847245, 42183.57816421835, 43682.563174368246, 42706.7864660677, 43105.568944310544, 43175.3523697144, 43205.35191972115, 43405.91485212871, 9438.622455101788, 9567.665131720356, 9587.376820506679, 9608.567614457366, 9613.855792163124, 9420.434773913554, 9539.379940303892, 9539.666111686092, 9546.904530954705, 9547.474888881108, 9476.384035037734, 9549.952250238774, 9553.426794392324, 9571.18644915183, 9572.32993690442, 9463.384879982814, 9516.286278529138, 9518.0, 9540.570674319602, 9552.140307372336, 9398.0, 9466.148046675764, 9468.668596599086, 9483.715488535356, 9492.288078394147, 40133.99665008398, 40317.0, 40400.1919961602, 40555.18889622221, 40567.18865622671, 3467.583889933199, 3519.9120021999397, 3553.875614353485, 3797.677197438221, 3950.280777322931, 23104.0, 28671.56992645112, 28900.710992890014, 29357.85321073402, 29912.80348786046, 3170.2074481379655, 3183.6338821035647, 3187.9043628691174, 3207.1661368044242, 3275.8853440129483, 453222.0, 461595.0, 462078.6896065532, 462945.6852715749, 471895.0, 56664.71667641718, 57052.58316418922, 57117.42882571204, 57156.71421642865, 57170.42829571734, 34188.316233675185, 34959.12602184965, 61618.69190654018, 61892.69053654703, 62010.0698489523, 15276.0, 15713.68572628541, 15758.0, 15764.290606922716, 15798.447054353042, 9970.950145249373, 12258.957988570894, 12314.630561083182, 12319.260844349368, 12362.567310144103, 18955.14701838393, 18996.480281577442, 19017.714734278998, 19051.428457146307, 19081.2367505301, 6756.797296081126, 8086.474379165335, 8215.178482151738, 8266.917330826735, 8341.374396920255, 843.8354520868372, 975.23444096384, 981.5484876956538, 1025.600015993763, 1050.0234781652982, 28550.42899142047, 28973.696183671364, 29938.850305748332, 30819.69180308168, 31153.68846311508, 862.9395942283979, 1070.5824728355947, 1077.876044254905, 1080.5029686344212, 1151.5451396698425, 14490.927545362416, 14662.780058299135, 14707.41170353174, 15082.321295541728, 15198.696026079417, 637585.0, 641826.0, 642201.0, 642292.0, 643498.0, 42330.0, 55562.44437555573, 56056.0, 56106.0, 56260.71869640626, 1270.0538099116034, 1312.8293321868096, 1317.960461186166, 1367.1250399744172, 1435.8564143585768, 25017.74982250154, 28191.71808281891, 29823.850880746326, 29889.850550747105, 30789.768409264274, 48248.793780154374, 48835.755821220664, 49012.098790120705, 49061.773455662464, 49149.2627610579, 504278.0, 508510.0, 509809.0, 513043.0, 625247.0, 38308.23383532369, 39327.0, 39824.80525584237, 40104.39843402293, 40183.19633607253, 36739.0, 45040.0, 45107.0, 45373.0, 46033.84915377142, 37497.25005499929, 38091.80954095212, 38418.80790596029, 38628.420573690855, 39313.606863930996, 29176.7082329174, 37547.62452375441, 37787.0, 38287.0, 40537.0, 36264.456033159, 36631.16076970826, 37287.627123728416, 37460.250794984495, 37509.43735844019, 26261.645674321673, 31490.0, 33949.981500555026, 33957.66042339545, 34306.79926202571, 24646.630300545865, 24743.752562474147, 24852.37869053288, 25426.618600721373, 25451.36371590725], "value": 19688.034554260466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.5493036988352, 370.26317627920446, 370.6978812268001, 378.36434789553556, 397.05698964958225, 15029.774553381694, 15378.154201518912, 15757.84242157578, 19859.503512412197, 20951.16195352184, 18029.90985045073, 20148.697769533468, 20239.898800506013, 20742.170313187453, 20926.895365523153, 5602.65536271294, 5945.227120474332, 6254.155688981975, 6261.27995280544, 6284.5600807943465, 5920.319163296212, 6172.0, 6199.194104766397, 6229.567199544085, 6243.937560624404, 5871.1193321001765, 6107.297660769002, 6206.7827626033095, 6210.813675589717, 6213.658248796314, 6095.939040609582, 6110.602810817305, 6131.417515336036, 6140.324564297924, 6140.754369825202, 5051.419086804991, 5271.0248604008, 5277.920831187535, 5309.495597918191, 5320.866978325573, 26548.73451265482, 26711.0, 26758.464830703277, 26787.196384108312, 27142.0, 2337.6376661617305, 2442.3283597010786, 2499.787518060967, 2520.3195137312823, 3085.475469170224, 13313.534026309033, 15345.46290879814, 15705.293261803246, 16209.0, 16250.75623865643, 1748.0, 1801.9639607207782, 1805.4583624912473, 1880.990595047016, 1924.980750192494, 102055.0, 106708.46645766721, 114280.0, 116870.0, 119211.0, 10204.642837500653, 10594.046535811814, 10668.946655266674, 10987.725306867393, 11190.94404527972, 13645.953107033825, 13649.249291289074, 13681.521146760015, 13685.178889266477, 13703.588892333246, 8309.418340716093, 8563.314934805201, 8589.65641374338, 8724.302055835518, 8748.78128046804, 7032.859342813115, 8547.188017138362, 8550.700725474579, 8576.44253123545, 8747.387682862138, 5418.383052485253, 5502.339719233718, 5694.288213973254, 6597.241317248482, 6766.594004359656, 1815.8547316214672, 1862.6647203503499, 1865.4403678896276, 1866.7106598477258, 1871.709884967832, 870.7866572689605, 908.1281969309405, 933.4772527384687, 1014.8782146142363, 1045.6235755128148, 20985.895070524854, 21993.67009494859, 22461.4384640382, 22581.548369032527, 22592.75739834292, 969.0842154164388, 974.7758015656439, 994.3337963564429, 1015.8730158730159, 1039.2257767962917, 8910.153535414254, 9244.90755092454, 11310.773784524263, 11826.763464730657, 11938.64184074479, 499924.0, 504817.0, 563100.0, 568231.0, 569702.0, 89147.55426222828, 116029.0, 118466.40766796283, 120636.39681801535, 121784.39107804405, 374.51500307102367, 436.03853503025755, 465.6763549333249, 467.0006186760336, 467.5978658353813, 9689.885663148829, 9889.703308900744, 10235.925227850968, 11457.541698332307, 11507.539698411969, 36486.817565912, 50072.748181295756, 52181.7390913043, 56485.71757141188, 56563.0, 62658.68670656617, 72579.91130132947, 81815.59092204501, 82068.0, 82246.0, 5565.8051968182535, 5673.687947162945, 7975.96612863716, 7997.880031799411, 8324.292435143081, 10364.740881478023, 12235.816462753244, 12992.480300787862, 13189.80215296752, 13290.20258784476, 9990.901000890042, 10762.139028877515, 10789.514471848628, 11342.886571134182, 11690.357030363071, 5071.594272458268, 8232.300254478436, 10158.0, 11353.015613634607, 11602.477888495207, 5753.568482363923, 8804.60379282921, 9931.950340248253, 11484.966353028267, 11742.530298787953, 7703.614819259126, 7963.840723185388, 8239.217274358927, 8407.915920840713, 8429.283510901641, 9478.478683672189, 11130.0, 11187.440627968732, 11553.480093395649, 11561.710957226136], "value": 9177.884107003569, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.8082268482422, 1438.0, 1502.241368109105, 1702.2765324736984, 1945.4747218251082, 7036.40190583801, 7412.0, 7718.343940765035, 8317.043539992903, 8321.0, 8129.796755081126, 8206.425550211488, 8208.507489550617, 8222.465539739927, 8245.96925384327, 2234.3520379090087, 2260.4122928038664, 2262.4457008032973, 2263.0155882191298, 2263.2418139923147, 2249.460129568908, 2292.805111565519, 2310.653401989698, 2313.1672597864754, 2314.8032417244553, 1841.530409745513, 2223.288547664748, 2257.604919139152, 2266.3767463947383, 2269.2624896908487, 2202.3503066595395, 2220.5891909996703, 2259.9322020339414, 2269.841111122222, 2270.489139943508, 2270.1600407849037, 2300.3903965449167, 2303.67748515208, 2304.135949019118, 2306.411864974421, 8152.592370381457, 8227.712030078977, 8230.506169629782, 8252.917470825336, 8311.085780564134, 5007.974960125212, 5204.791808327663, 5206.76569554371, 5214.0, 5214.217867319894, 3321.4187517184396, 3521.841517131735, 3525.7355698322726, 3586.211033572636, 3600.6039335673054, 962.8507581324906, 1000.848176420694, 1051.8579991701176, 1062.8033813744482, 1071.7159952612565, 10582.153427725763, 10626.0, 10709.839352409721, 10715.946420267926, 10741.785164296749, 4141.958580414218, 4295.22685916538, 4387.4954380246045, 4389.956100439019, 4391.978040109779, 8346.8330633387, 8365.498070115813, 8379.37154713399, 8382.07797142314, 8390.538520381439, 4695.624350051988, 5024.547790698782, 5026.271190677405, 5032.521910418504, 5032.943081952758, 4226.978865105655, 4252.978735106305, 4274.658027357804, 4379.233634114081, 4563.657725670588, 3234.9676503235137, 3378.847951842173, 3553.804540750232, 3564.875229367012, 3587.336342776595, 1020.7601213714771, 1103.2773533335678, 1110.189561620022, 1111.9054880335261, 1113.2151832957745, 825.8232019372371, 848.3382961290204, 892.5849479991834, 898.7842917699705, 930.9115634014757, 5292.52367286946, 5407.324084489439, 5674.148877668383, 5683.857903552362, 5693.316801983706, 947.5688561704408, 980.2794945714894, 992.1318846009741, 998.8601595776597, 1003.508280942333, 3619.6561326673923, 3664.2488289900507, 3671.6879065279218, 3771.641694039062, 3784.7073046449764, 8532.829343413097, 8997.325200609981, 9497.43015419077, 9827.459489727991, 9947.45289009097, 9355.625774969061, 9753.609855605697, 9887.0, 9888.357256778287, 9897.455639939872, 6494.0, 7561.7731468056045, 7676.961615192, 7770.844583108306, 7784.987951566261, 4939.777710002986, 5377.704226267437, 5466.442064011897, 5484.7806087757635, 6016.759329626766, 8871.290296776113, 9228.123328283802, 9375.531223438937, 9380.155785979021, 9398.06019398082, 7191.820204494929, 9263.444193348421, 9323.860142097737, 9453.385529940531, 9474.289428292768, 6571.605703657796, 6779.949107888286, 7025.156981162293, 7192.640367981685, 7194.244604316681, 7620.695172193272, 7826.491278066907, 7898.644560994884, 7927.603619819101, 7946.960265198637, 6592.868142637025, 6677.232118306457, 6993.825154371181, 7138.964305178441, 7261.346478816932, 6857.485688573277, 6916.273791251846, 6930.0, 6962.547434416747, 6999.8950015750825, 5910.320313164041, 6373.107764912823, 6535.44448721864, 6546.705398256994, 6645.202575690948, 4629.722216666876, 4673.7896794645, 4778.450478195052, 4796.088743138793, 4928.697772492367, 4737.692050016738, 4851.442084160367, 4886.92669609949, 4894.828680996148, 4919.606431485401], "value": 4293.870175310352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.9443252998194, 301.58532018474597, 311.54514408962905, 316.94928811390184, 319.21154747772994, 4152.979235103824, 4419.58013988671, 5273.2617433559335, 5301.072312345339, 5319.494648008433, 4143.73065750726, 4219.0, 4946.0602485527725, 4964.7765850536725, 5271.65734227275, 3872.0, 3957.9010524736896, 4042.443659191203, 4044.352903535435, 4105.815238314268, 3683.3922402803505, 4027.637512623863, 4054.128362402083, 4070.7354021988476, 4107.691923105779, 3939.330313846653, 3974.8671628585857, 4012.638862502374, 4045.797710114497, 4065.227606754722, 3445.5693038370077, 3527.9945215613425, 3840.1935593525395, 4035.3366777926426, 4065.5934406559404, 3522.5420695309704, 3535.681788639009, 3537.663921927413, 3540.3804334241663, 3541.3625547401457, 4577.816887324503, 4818.819389249621, 5103.642745007851, 5130.230465430178, 5141.562967147797, 4380.364847097185, 4602.079584083163, 4626.699264547794, 4632.540749663879, 4644.2816158021415, 5451.236826844205, 5452.836414907559, 5469.0, 5471.042567550663, 5503.229547863302, 3280.311134661724, 3873.1479074603544, 3882.378819388884, 3888.863889763845, 3904.297226499229, 4817.927731084036, 5208.791648334024, 5220.797258207142, 5346.866328341823, 5818.41815818419, 3722.571904230994, 3807.5430948286385, 3811.4282857571034, 3814.5613254475534, 3839.827207775657, 3248.642649308574, 3561.2343346180696, 3860.942085868714, 3866.38137897935, 3900.180961998012, 3447.7069449096607, 3806.790626515513, 4296.324433470949, 4461.933071003938, 4543.795529201194, 5398.001369746609, 5439.918401223985, 5556.722163891789, 5585.469380408855, 5613.158026296007, 1235.2403271987662, 1303.4330066421137, 1320.8811206991418, 1325.4300650720284, 1332.433715670836, 695.3637421759134, 840.9285210757032, 861.0657436681146, 876.412803421709, 898.725888603969, 5253.579713622901, 5271.6309858310315, 5291.947080529146, 5308.309919710413, 5312.123499622519, 5279.234510995884, 5313.44208858072, 5684.516816070598, 5736.397678243808, 5752.398081534756, 4660.555227879368, 4745.0, 5105.923411148836, 5164.7934082636275, 5267.868303292448, 2345.0619752099296, 2918.795684302121, 2929.428761391508, 2933.530635098374, 2935.9559606605917, 4639.932815452465, 5251.291075704731, 5588.133839254922, 5703.914441283384, 5747.971260143672, 4530.705504142286, 5905.291365036137, 5975.521958243417, 5992.490638295793, 6005.759769609254, 4862.513748625109, 4866.270059491105, 5888.440598143169, 6000.369961154103, 6024.879502409927, 4700.92948605778, 5731.910936921971, 5751.4536119068625, 5761.855953601026, 5823.388544202798, 5391.840754237857, 5635.8591035224445, 5646.447227012604, 5662.85842853932, 5699.29021293602, 4735.289706544046, 5196.506331898464, 5496.80761173357, 5828.737706803119, 5932.614380065282, 3720.2656047804303, 3844.6928044465017, 3861.208452267222, 3878.166194268246, 3893.571707112272, 5747.827565173052, 5757.5393968481585, 5778.971105144616, 5800.970995144998, 5821.621594596338, 4467.994701192336, 4868.853934381974, 5029.497050294941, 5634.408387119457, 5651.0, 5479.726013699219, 5584.916226256691, 5626.774929002794, 5630.943690563206, 5640.830775076754, 4037.6971727119994, 4647.86056418308, 5238.485526947163, 5504.944950550604, 5533.087040638249, 4727.747147006115, 4904.376158755049, 5061.569766569883, 5169.379674439091, 5480.479354461319, 4600.539946005372, 5103.851633382459, 5241.528262456397, 5349.4650534946195, 5509.834704958857], "value": 4009.4522270005396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.637087826965, 774.8992630957974, 791.5725508225557, 814.1936447109761, 869.9391042627019, 14482.420703171874, 15070.246487675628, 15120.092794432341, 15177.241137943087, 15225.238738063093, 13619.863801361984, 13751.449942002333, 13777.51778687746, 13791.793123103162, 14235.71528569428, 682.778097118436, 712.8859382498802, 717.1107826295382, 871.4553404122423, 915.2632131134434, 882.867569864519, 895.928325733943, 898.6989358564889, 899.1952202778509, 901.062894589626, 961.2022021721954, 962.0764066496137, 964.3924327673556, 966.183574879226, 967.9951600242024, 733.0946281342538, 762.6949220311864, 767.4627760567569, 958.420155805738, 960.0063933828488, 567.5998421113105, 568.5309619563876, 689.4174422612884, 720.4056653261081, 720.8125887269296, 15079.170645614488, 15086.0, 15087.39650413982, 15090.39638414462, 15119.773203401957, 11235.325880447117, 13444.32778361078, 13469.865301347058, 13615.795763063563, 13734.93132534331, 10804.351738895613, 12337.691557711129, 12354.876451235552, 12388.504459821595, 12872.678183045406, 3864.8260828262796, 4466.955330446686, 4549.45406551216, 4549.772511374418, 4595.379623750784, 86026.0, 86373.70439443413, 87658.12341876564, 89245.0, 90467.09532904653, 10728.570857165625, 10765.084967777826, 10817.350958942488, 10843.83734243987, 11077.169212309109, 20811.375658730263, 20815.687764683542, 21023.8948805255, 21417.89291053566, 21419.785802141778, 7607.467477276648, 7658.693652253847, 7698.230176982257, 7700.422468314899, 7910.248526389983, 4276.935845962313, 4389.670774691911, 4393.0994146199955, 4398.956010439919, 4456.75487848165, 3346.113279980807, 3354.07762865214, 3382.0530251529613, 3452.5339079224486, 3454.4300190468794, 628.1080865171467, 632.867097909435, 636.3541005879036, 649.1236830279086, 651.0852252585125, 593.7654626422625, 598.1446531460053, 620.7485968182893, 638.6040654793965, 642.0754114075716, 8541.530215838064, 8834.602442890086, 8849.0, 8882.644694212158, 9002.324825638103, 602.7877688881814, 616.5745635511482, 624.622103627308, 632.0456111272013, 648.4941745438565, 3359.546461227752, 4269.402283680287, 4305.784710764449, 4427.912513018921, 4463.642908567372, 79789.20210797933, 93799.53100234455, 111923.44038279756, 115458.0, 121568.0, 98109.0, 102483.4875825631, 103548.0, 104767.95232047734, 105899.0, 83753.58123209345, 83857.58071209605, 86371.56814215888, 89625.0, 94613.0, 8173.591320434073, 8442.731783286226, 8510.48937063778, 8760.25537829266, 8835.249003834746, 52657.736711316196, 52755.417337479936, 53533.464665354404, 54513.18230226471, 55006.44993550173, 61681.0, 66421.33578664152, 66922.66538667471, 75210.24789752033, 78141.0, 12262.632121036384, 12575.056870734914, 12698.238105713686, 12730.490780369051, 12825.551105711254, 14242.940349866858, 14320.57038288853, 14710.705785884436, 14816.40734370613, 14869.107853528823, 13203.481599615783, 13212.0, 13292.20246785196, 13830.23933683657, 14076.366563504871, 13229.669258268621, 13415.329233538088, 13439.798403023768, 13553.796693049415, 13833.792493112813, 12777.361131943551, 12979.41592628353, 13323.533676321282, 13455.394507247001, 13549.051566390652, 9627.326087173831, 10913.110370890228, 11253.606123785628, 11293.943530282297, 11358.943205284251, 9834.75413114678, 11181.32912025281, 11202.66392008241, 11292.266002709797, 11441.313521188755], "value": 7423.353593014364, "unit": "score"}], "name": "dromaeo_css", "value": 7115.754281190003, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 112.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 141.0, 141.0, 152.0, 112.0, 113.0, 113.0, 114.0, 146.0, 120.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 184.0, 185.0, 183.0, 180.0, 183.0, 184.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 62.0, 58.0, 59.0, 59.0, 84.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 95.0, 91.0, 98.0, 101.0, 101.0, 67.0, 68.0, 90.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 92.0, 110.0, 152.0, 149.0, 92.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 79.0, 82.0, 84.0, 90.0, 137.0, 131.0, 85.0, 84.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 57.0, 55.0, 57.0, 55.0, 55.0, 54.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 69.0, 67.0, 72.0, 75.0, 69.0, 69.0, 67.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 85.0, 87.0, 86.0, 98.0, 97.0, 89.0, 85.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 149.0, 132.0, 175.0, 156.0, 157.0, 239.0, 144.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 172.0, 180.0, 172.0, 170.0, 168.0, 173.0, 171.0, 170.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 66.0, 67.0, 66.0, 68.0, 65.0, 62.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1480.4, "alertThreshold": 2.0}]} 09:50:25 INFO - Return code: 0 09:50:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:50:25 INFO - # TBPL SUCCESS # 09:50:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:25 INFO - 2016-10-29 09:50:25.603 screenresolution[1663:9721] starting screenresolution argv=screenresolution get 09:50:25 INFO - 2016-10-29 09:50:25.605 screenresolution[1663:9721] Display 0: 1600x1200x32@60 09:50:25 INFO - 2016-10-29 09:50:25.611 screenresolution[1664:9724] starting screenresolution argv=screenresolution list 09:50:25 INFO - Available Modes on Display 0 09:50:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:25 INFO - Intel Iris: 09:50:25 INFO - Chipset Model: Intel Iris 09:50:25 INFO - Type: GPU 09:50:25 INFO - Bus: Built-In 09:50:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:25 INFO - Vendor: Intel (0x8086) 09:50:25 INFO - Device ID: 0x0a2e 09:50:25 INFO - Revision ID: 0x0009 09:50:25 INFO - Displays: 09:50:25 INFO - Display: 09:50:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:25 INFO - Display Serial Number: 1600x1200 60 09:50:25 INFO - Main Display: Yes 09:50:25 INFO - Mirror: Off 09:50:25 INFO - Online: Yes 09:50:25 INFO - Rotation: Supported 09:50:25 INFO - Return code: 0 09:50:25 INFO - Running post-action listener: _resource_record_post_action 09:50:25 INFO - [mozharness: 2016-10-29 16:50:25.845422Z] Finished run-tests step (success) 09:50:25 INFO - Running post-run listener: _resource_record_post_run 09:50:25 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38421504; Write bytes: 708972544; Read time: 380; Write time: 2371 09:50:25 INFO - TinderboxPrint: CPU usage
53.5% 09:50:25 INFO - TinderboxPrint: I/O read bytes / time
38,421,504 / 380 09:50:25 INFO - TinderboxPrint: I/O write bytes / time
708,972,544 / 2,371 09:50:25 INFO - TinderboxPrint: CPU idle
2,258.9 (46.5%) 09:50:25 INFO - TinderboxPrint: CPU system
973.9 (20.0%) 09:50:25 INFO - TinderboxPrint: CPU user
1,624.9 (33.5%) 09:50:25 INFO - TinderboxPrint: Swap in / out
444,932,096 / 0 09:50:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402004480; Write bytes: 396920320; Read time: 29484; Write time: 826 09:50:25 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36307968; Write bytes: 297589248; Read time: 357; Write time: 1504 09:50:26 INFO - Running post-run listener: _upload_blobber_files 09:50:26 INFO - Blob upload gear active. 09:50:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:50:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:27 INFO - (blobuploader) - INFO - Done attempting. 09:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:28 INFO - (blobuploader) - INFO - Done attempting. 09:50:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:50:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:31 INFO - (blobuploader) - INFO - Done attempting. 09:50:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:31 INFO - Return code: 0 09:50:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2dd9d9fc1ec621fe87a736fd998b06a2f2604184a9a1328bfd25558d56280dfa6a31d66f22dc4fb51567c2c975d39d3494e78411a542cc78de1be3a68b62b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331cb7cd11b40c4da6e6463e85868f30925d1bb63ea44d758736ee79156f1defeb3d4095067ea3c094d01faa8caf78766c6ca6c7f916a8611c0cab6ca4ecebe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40eb8860c40a6fc0c538c715abfdd9ded6386059fba546a0f9715809e8969ddaf01c59baf28ff5b5d70b7671d3e18aa2fb407af2c111f638d168a862550d0d83"} 09:50:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:31 INFO - Contents: 09:50:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2dd9d9fc1ec621fe87a736fd998b06a2f2604184a9a1328bfd25558d56280dfa6a31d66f22dc4fb51567c2c975d39d3494e78411a542cc78de1be3a68b62b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331cb7cd11b40c4da6e6463e85868f30925d1bb63ea44d758736ee79156f1defeb3d4095067ea3c094d01faa8caf78766c6ca6c7f916a8611c0cab6ca4ecebe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40eb8860c40a6fc0c538c715abfdd9ded6386059fba546a0f9715809e8969ddaf01c59baf28ff5b5d70b7671d3e18aa2fb407af2c111f638d168a862550d0d83"} 09:50:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:31 INFO - Copying logs to upload dir... 09:50:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.391889 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-29 09:50:31.323409) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 09:50:31.327436) ========= 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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/7c2dd9d9fc1ec621fe87a736fd998b06a2f2604184a9a1328bfd25558d56280dfa6a31d66f22dc4fb51567c2c975d39d3494e78411a542cc78de1be3a68b62b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331cb7cd11b40c4da6e6463e85868f30925d1bb63ea44d758736ee79156f1defeb3d4095067ea3c094d01faa8caf78766c6ca6c7f916a8611c0cab6ca4ecebe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40eb8860c40a6fc0c538c715abfdd9ded6386059fba546a0f9715809e8969ddaf01c59baf28ff5b5d70b7671d3e18aa2fb407af2c111f638d168a862550d0d83"} build_url:https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/fYfjAGj7RwKJmsTjSzJ8yA/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/7c2dd9d9fc1ec621fe87a736fd998b06a2f2604184a9a1328bfd25558d56280dfa6a31d66f22dc4fb51567c2c975d39d3494e78411a542cc78de1be3a68b62b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331cb7cd11b40c4da6e6463e85868f30925d1bb63ea44d758736ee79156f1defeb3d4095067ea3c094d01faa8caf78766c6ca6c7f916a8611c0cab6ca4ecebe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40eb8860c40a6fc0c538c715abfdd9ded6386059fba546a0f9715809e8969ddaf01c59baf28ff5b5d70b7671d3e18aa2fb407af2c111f638d168a862550d0d83"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 09:50:31.360706) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:50:31.361036) ========= 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.pwYSTJpn4S/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.xfCYr4kkHd/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.004940 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 09:50:31.409486) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 09:50:31.409795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 09:50:31.410101) ========= ========= Total master_lag: 0.17 =========