builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0090 starttime: 1472497838.95 results: success (0) buildid: 20160829095731 builduid: 58530b7bb9fb404a85ea453aaed558f4 revision: 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:38.948494) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:38.949356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:38.950009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:38.975316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:38.976045) ========= 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.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-08-29 12:10:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.7M=0.001s 2016-08-29 12:10:39 (13.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185626 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:39.189210) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:39.190012) ========= 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.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.020535 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:39.242780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-29 12:10:39.243576) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 --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 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-08-29 12:10:39,322 truncating revision to first 12 chars 2016-08-29 12:10:39,323 Setting DEBUG logging. 2016-08-29 12:10:39,323 attempt 1/10 2016-08-29 12:10:39,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2ec253835380?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 12:10:41,209 unpacking tar archive at: fx-team-2ec253835380/testing/mozharness/ program finished with exit code 0 elapsedTime=2.137302 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-29 12:10:41.405176) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:41.405565) ========= script_repo_revision: 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:41.406005) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:41.406328) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 12:10:41.425891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-29 12:10:41.426624) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:10:41 INFO - MultiFileLogger online at 20160829 12:10:41 in /builds/slave/test 12:10:41 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 12:10:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:41 INFO - {'append_to_log': False, 12:10:41 INFO - 'base_work_dir': '/builds/slave/test', 12:10:41 INFO - 'blob_upload_branch': 'Fx-Team', 12:10:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:41 INFO - 'branch': 'Fx-Team', 12:10:41 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:41 INFO - 'config_files': ('talos/mac_config.py',), 12:10:41 INFO - 'default_actions': ('clobber', 12:10:41 INFO - 'read-buildbot-config', 12:10:41 INFO - 'download-and-extract', 12:10:41 INFO - 'populate-webroot', 12:10:41 INFO - 'create-virtualenv', 12:10:41 INFO - 'install', 12:10:41 INFO - 'run-tests'), 12:10:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:41 INFO - 'download_minidump_stackwalk': True, 12:10:41 INFO - 'download_symbols': 'ondemand', 12:10:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:10:41 INFO - 'tooltool.py': '/tools/tooltool.py', 12:10:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:10:41 INFO - '/tools/misc-python/virtualenv.py')}, 12:10:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:41 INFO - 'installer_path': 'installer.exe', 12:10:41 INFO - 'log_level': 'info', 12:10:41 INFO - 'log_name': 'talos', 12:10:41 INFO - 'log_to_console': True, 12:10:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:10:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:10:41 INFO - 'opt_config_files': (), 12:10:41 INFO - 'pip_index': False, 12:10:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:41 INFO - 'cmd': ('bash', 12:10:41 INFO - '-c', 12:10:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:41 INFO - 'enabled': True, 12:10:41 INFO - 'halt_on_failure': False, 12:10:41 INFO - 'name': 'check_screen_resolution'},), 12:10:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:41 INFO - 'cmd': ('bash', 12:10:41 INFO - '-c', 12:10:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:41 INFO - 'enabled': True, 12:10:41 INFO - 'halt_on_failure': False, 12:10:41 INFO - 'name': 'check_screen_resolution'},), 12:10:41 INFO - 'run_cmd_checks_enabled': True, 12:10:41 INFO - 'sps_profile': False, 12:10:41 INFO - 'sps_profile_interval': 0, 12:10:41 INFO - 'suite': 'dromaeojs', 12:10:41 INFO - 'system_bits': '32', 12:10:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:41 INFO - 'title': 't-yosemite-r7-0090', 12:10:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:41 INFO - 'use_talos_json': True, 12:10:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:41 INFO - 'work_dir': 'build'} 12:10:41 INFO - [mozharness: 2016-08-29 19:10:41.624932Z] Running clobber step. 12:10:41 INFO - Running pre-action listener: _resource_record_pre_action 12:10:41 INFO - Running main action method: clobber 12:10:41 INFO - rmtree: /builds/slave/test/build 12:10:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:43 INFO - Running post-action listener: _resource_record_post_action 12:10:43 INFO - [mozharness: 2016-08-29 19:10:43.109004Z] Finished clobber step (success) 12:10:43 INFO - [mozharness: 2016-08-29 19:10:43.109266Z] Running read-buildbot-config step. 12:10:43 INFO - Running pre-action listener: _resource_record_pre_action 12:10:43 INFO - Running main action method: read_buildbot_config 12:10:43 INFO - Using buildbot properties: 12:10:43 INFO - { 12:10:43 INFO - "project": "", 12:10:43 INFO - "product": "firefox", 12:10:43 INFO - "script_repo_revision": "production", 12:10:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:10:43 INFO - "repository": "", 12:10:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:10:43 INFO - "buildid": "20160829095731", 12:10:43 INFO - "pgo_build": "False", 12:10:43 INFO - "basedir": "/builds/slave/test", 12:10:43 INFO - "buildnumber": 129, 12:10:43 INFO - "slavename": "t-yosemite-r7-0090", 12:10:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:10:43 INFO - "platform": "yosemite_r7", 12:10:43 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:10:43 INFO - "branch": "fx-team", 12:10:43 INFO - "repo_path": "integration/fx-team", 12:10:43 INFO - "revision": "2ec2538353807a17b8b23f2440c9a52ec3f3ccc5", 12:10:43 INFO - "stage_platform": "macosx64", 12:10:43 INFO - "builduid": "58530b7bb9fb404a85ea453aaed558f4", 12:10:43 INFO - "slavebuilddir": "test" 12:10:43 INFO - } 12:10:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:10:43 INFO - Running post-action listener: _resource_record_post_action 12:10:43 INFO - [mozharness: 2016-08-29 19:10:43.114952Z] Finished read-buildbot-config step (success) 12:10:43 INFO - [mozharness: 2016-08-29 19:10:43.115163Z] Running download-and-extract step. 12:10:43 INFO - Running pre-action listener: _resource_record_pre_action 12:10:43 INFO - Running main action method: download_and_extract 12:10:43 INFO - mkdir: /builds/slave/test/build/tests 12:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:43 INFO - trying https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 12:10:44 INFO - Downloaded 1199 bytes. 12:10:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:10:44 INFO - Using the following test package requirements: 12:10:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:10:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:10:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:10:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'jsshell-mac.zip'], 12:10:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:10:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:10:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:10:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:10:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:10:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:10:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:10:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:44 INFO - trying https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:44 INFO - Downloading https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:10:45 INFO - Downloaded 16369917 bytes. 12:10:45 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:10:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:52 INFO - trying https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:52 INFO - Downloading https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:10:53 INFO - Downloaded 16369917 bytes. 12:10:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:00 INFO - trying https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:11:00 INFO - Downloading https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:11:00 INFO - Downloaded 14413998 bytes. 12:11:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:11:01 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')]} 12:11:01 INFO - trying https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:01 INFO - Downloading https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 12:11:04 INFO - Downloaded 111525580 bytes. 12:11:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:04 INFO - mkdir: /builds/slave/test/properties 12:11:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:04 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:04 INFO - Contents: 12:11:04 INFO - build_url:https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:11:04 INFO - Running post-action listener: _resource_record_post_action 12:11:04 INFO - Running post-action listener: set_extra_try_arguments 12:11:04 INFO - [mozharness: 2016-08-29 19:11:04.769988Z] Finished download-and-extract step (success) 12:11:04 INFO - [mozharness: 2016-08-29 19:11:04.770094Z] Running populate-webroot step. 12:11:04 INFO - Running pre-action listener: _resource_record_pre_action 12:11:04 INFO - Running main action method: populate_webroot 12:11:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:04 INFO - '--tppagecycles', 12:11:04 INFO - '7'], 12:11:04 INFO - 'tests': ['tsvgm']}}, 12:11:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['damp', 'tps']}, 12:11:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['damp', 'tps']}, 12:11:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['dromaeo_dom']}, 12:11:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['basic_compositor_video']}, 12:11:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:11:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore', 12:11:04 INFO - 'tabpaint']}, 12:11:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['tsvgx', 12:11:04 INFO - 'tsvgr_opacity', 12:11:04 INFO - 'tart', 12:11:04 INFO - 'tscrollx', 12:11:04 INFO - 'cart']}, 12:11:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:04 INFO - 'tsvgr_opacity', 12:11:04 INFO - 'tart', 12:11:04 INFO - 'tscrollx', 12:11:04 INFO - 'cart']}, 12:11:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--disable-e10s'], 12:11:04 INFO - 'tests': ['tp5o']}, 12:11:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['tp5o']}, 12:11:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--disable-e10s', 12:11:04 INFO - '--xperf_path', 12:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:04 INFO - 'tests': ['tp5n']}, 12:11:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--xperf_path', 12:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:04 INFO - 'tests': ['tp5n']}}, 12:11:04 INFO - 'talos.zip': {'path': '', 12:11:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:04 INFO - Running post-action listener: _resource_record_post_action 12:11:04 INFO - [mozharness: 2016-08-29 19:11:04.789015Z] Finished populate-webroot step (success) 12:11:04 INFO - [mozharness: 2016-08-29 19:11:04.789148Z] Running create-virtualenv step. 12:11:04 INFO - Running pre-action listener: _resource_record_pre_action 12:11:04 INFO - Running main action method: create_virtualenv 12:11:04 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:04 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 12:11:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:04 INFO - Using real prefix '/tools/python27' 12:11:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:05 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:08 INFO - Installing pip.................done. 12:11:08 INFO - Return code: 0 12:11:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:08 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 12:11:08 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 12:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:08 INFO - 'HOME': '/Users/cltbld', 12:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:08 INFO - 'LOGNAME': 'cltbld', 12:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:08 INFO - 'MOZ_NO_REMOTE': '1', 12:11:08 INFO - 'NO_EM_RESTART': '1', 12:11:08 INFO - 'PAGER': '/bin/cat', 12:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:08 INFO - 'PWD': '/builds/slave/test', 12:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:08 INFO - 'SHELL': '/bin/bash', 12:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:08 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:08 INFO - 'USER': 'cltbld', 12:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:08 INFO - 'XPC_FLAGS': '0x0', 12:11:08 INFO - 'XPC_SERVICE_NAME': '0', 12:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:08 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 12:11:08 INFO - Cleaning up... 12:11:08 INFO - Return code: 0 12:11:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:08 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 12:11:08 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 12:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:08 INFO - 'HOME': '/Users/cltbld', 12:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:08 INFO - 'LOGNAME': 'cltbld', 12:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:08 INFO - 'MOZ_NO_REMOTE': '1', 12:11:08 INFO - 'NO_EM_RESTART': '1', 12:11:08 INFO - 'PAGER': '/bin/cat', 12:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:08 INFO - 'PWD': '/builds/slave/test', 12:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:08 INFO - 'SHELL': '/bin/bash', 12:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:08 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:08 INFO - 'USER': 'cltbld', 12:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:08 INFO - 'XPC_FLAGS': '0x0', 12:11:08 INFO - 'XPC_SERVICE_NAME': '0', 12:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:08 INFO - Downloading/unpacking psutil>=3.1.1 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:11 INFO - Installing collected packages: psutil 12:11:11 INFO - Running setup.py install for psutil 12:11:12 INFO - building 'psutil._psutil_osx' extension 12:11:12 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 12:11:12 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 12:11:12 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 12:11:12 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 12:11:12 INFO - building 'psutil._psutil_posix' extension 12:11:12 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 12:11:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:12 INFO - ^ 12:11:12 INFO - 1 warning generated. 12:11:12 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 12:11:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:12 INFO - Successfully installed psutil 12:11:12 INFO - Cleaning up... 12:11:12 INFO - Return code: 0 12:11:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:12 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:12 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 12:11:12 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 12:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:12 INFO - 'HOME': '/Users/cltbld', 12:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:12 INFO - 'LOGNAME': 'cltbld', 12:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:12 INFO - 'MOZ_NO_REMOTE': '1', 12:11:12 INFO - 'NO_EM_RESTART': '1', 12:11:12 INFO - 'PAGER': '/bin/cat', 12:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:12 INFO - 'PWD': '/builds/slave/test', 12:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:12 INFO - 'SHELL': '/bin/bash', 12:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:12 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:12 INFO - 'USER': 'cltbld', 12:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:12 INFO - 'XPC_FLAGS': '0x0', 12:11:12 INFO - 'XPC_SERVICE_NAME': '0', 12:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:13 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 12:11:13 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 12:11:13 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 12:11:13 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 12:11:13 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 12:11:13 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 12:11:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:11:15 INFO - Installing collected packages: mozsystemmonitor 12:11:15 INFO - Running setup.py install for mozsystemmonitor 12:11:15 INFO - Successfully installed mozsystemmonitor 12:11:15 INFO - Cleaning up... 12:11:15 INFO - Return code: 0 12:11:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:11:15 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')]} 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 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')]} 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:15 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 12:11:15 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 12:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:15 INFO - 'HOME': '/Users/cltbld', 12:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:15 INFO - 'LOGNAME': 'cltbld', 12:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:15 INFO - 'MOZ_NO_REMOTE': '1', 12:11:15 INFO - 'NO_EM_RESTART': '1', 12:11:15 INFO - 'PAGER': '/bin/cat', 12:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:15 INFO - 'PWD': '/builds/slave/test', 12:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:15 INFO - 'SHELL': '/bin/bash', 12:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:15 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:15 INFO - 'USER': 'cltbld', 12:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:15 INFO - 'XPC_FLAGS': '0x0', 12:11:15 INFO - 'XPC_SERVICE_NAME': '0', 12:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:16 INFO - Downloading/unpacking jsonschema==2.5.1 12:11:16 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 12:11:16 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 12:11:16 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 12:11:16 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 12:11:16 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 12:11:16 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 12:11:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:11:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:11:18 INFO - warning: no files found matching '*.txt' 12:11:18 INFO - no previously-included directories found matching 'build' 12:11:18 INFO - no previously-included directories found matching 'dist' 12:11:18 INFO - no previously-included directories found matching '.git*' 12:11:18 INFO - Installing collected packages: jsonschema, functools32 12:11:19 INFO - Running setup.py install for functools32 12:11:19 INFO - warning: no files found matching '*.txt' 12:11:19 INFO - no previously-included directories found matching 'build' 12:11:19 INFO - no previously-included directories found matching 'dist' 12:11:19 INFO - no previously-included directories found matching '.git*' 12:11:19 INFO - Successfully installed jsonschema functools32 12:11:19 INFO - Cleaning up... 12:11:19 INFO - Return code: 0 12:11:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:11:19 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')]} 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 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')]} 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:19 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 12:11:19 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 12:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:19 INFO - 'HOME': '/Users/cltbld', 12:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:19 INFO - 'LOGNAME': 'cltbld', 12:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:19 INFO - 'MOZ_NO_REMOTE': '1', 12:11:19 INFO - 'NO_EM_RESTART': '1', 12:11:19 INFO - 'PAGER': '/bin/cat', 12:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:19 INFO - 'PWD': '/builds/slave/test', 12:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:19 INFO - 'SHELL': '/bin/bash', 12:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:19 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:19 INFO - 'USER': 'cltbld', 12:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:19 INFO - 'XPC_FLAGS': '0x0', 12:11:19 INFO - 'XPC_SERVICE_NAME': '0', 12:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:11:19 INFO - Cleaning up... 12:11:19 INFO - Return code: 0 12:11:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:19 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')]} 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 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')]} 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:19 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 12:11:19 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 12:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:19 INFO - 'HOME': '/Users/cltbld', 12:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:19 INFO - 'LOGNAME': 'cltbld', 12:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:19 INFO - 'MOZ_NO_REMOTE': '1', 12:11:19 INFO - 'NO_EM_RESTART': '1', 12:11:19 INFO - 'PAGER': '/bin/cat', 12:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:19 INFO - 'PWD': '/builds/slave/test', 12:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:19 INFO - 'SHELL': '/bin/bash', 12:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:19 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:19 INFO - 'USER': 'cltbld', 12:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:19 INFO - 'XPC_FLAGS': '0x0', 12:11:19 INFO - 'XPC_SERVICE_NAME': '0', 12:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:19 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:19 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 12:11:19 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 12:11:19 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 12:11:19 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 12:11:19 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 12:11:19 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 12:11:22 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 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 12:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 INFO - Downloading docopt-0.6.1.tar.gz 12:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:23 INFO - Running setup.py install for blobuploader 12:11:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:23 INFO - Running setup.py install for requests 12:11:24 INFO - Running setup.py install for docopt 12:11:24 INFO - Successfully installed blobuploader requests docopt 12:11:24 INFO - Cleaning up... 12:11:24 INFO - Return code: 0 12:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:24 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')]} 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 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')]} 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:24 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 12:11:24 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 12:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:24 INFO - 'HOME': '/Users/cltbld', 12:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:24 INFO - 'LOGNAME': 'cltbld', 12:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:24 INFO - 'MOZ_NO_REMOTE': '1', 12:11:24 INFO - 'NO_EM_RESTART': '1', 12:11:24 INFO - 'PAGER': '/bin/cat', 12:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:24 INFO - 'PWD': '/builds/slave/test', 12:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:24 INFO - 'SHELL': '/bin/bash', 12:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:24 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:24 INFO - 'USER': 'cltbld', 12:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:24 INFO - 'XPC_FLAGS': '0x0', 12:11:24 INFO - 'XPC_SERVICE_NAME': '0', 12:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:24 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-oDkPPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:24 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-EkzHpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:24 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AxicD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:24 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Y5rbZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CoP0t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FCXV19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-b45t0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-5Z9vrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-3lHOrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-U5whHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-O01b9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-j1yNEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Qttxbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:25 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AhoM7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:26 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-B5yI70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:26 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-YyHbL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:26 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AckhBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:26 INFO - Running setup.py install for manifestparser 12:11:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:26 INFO - Running setup.py install for mozcrash 12:11:26 INFO - Running setup.py install for mozdebug 12:11:26 INFO - Running setup.py install for mozdevice 12:11:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:26 INFO - Running setup.py install for mozfile 12:11:27 INFO - Running setup.py install for mozhttpd 12:11:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Running setup.py install for mozinfo 12:11:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Running setup.py install for mozInstall 12:11:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Running setup.py install for mozleak 12:11:27 INFO - Running setup.py install for mozlog 12:11:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:27 INFO - Running setup.py install for moznetwork 12:11:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Running setup.py install for mozprocess 12:11:28 INFO - Running setup.py install for mozprofile 12:11:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Running setup.py install for mozrunner 12:11:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Running setup.py install for mozscreenshot 12:11:28 INFO - Running setup.py install for moztest 12:11:28 INFO - Running setup.py install for mozversion 12:11:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:28 INFO - Cleaning up... 12:11:28 INFO - Return code: 0 12:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:28 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')]} 12:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 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')]} 12:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:28 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:28 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 12:11:28 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 12:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:28 INFO - 'HOME': '/Users/cltbld', 12:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:28 INFO - 'LOGNAME': 'cltbld', 12:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:28 INFO - 'MOZ_NO_REMOTE': '1', 12:11:28 INFO - 'NO_EM_RESTART': '1', 12:11:28 INFO - 'PAGER': '/bin/cat', 12:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:28 INFO - 'PWD': '/builds/slave/test', 12:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:28 INFO - 'SHELL': '/bin/bash', 12:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:28 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:28 INFO - 'USER': 'cltbld', 12:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:28 INFO - 'XPC_FLAGS': '0x0', 12:11:28 INFO - 'XPC_SERVICE_NAME': '0', 12:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-E_PAXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-hFQ6kL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-p53tJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ZdMxSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-BmGmfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-_6hQL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-uWL1Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:29 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)) 12:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:29 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-YMkzvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2G2lXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Z7DRJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ERUE3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-U7ZUWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-3D4KNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FLb_LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-uyqJLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-fzHN3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:30 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)) 12:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:30 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-kCYxLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:31 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)) 12:11:31 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)) 12:11:31 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)) 12:11:31 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)) 12:11:31 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)) 12:11:31 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)) 12:11:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:31 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 12:11:31 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 12:11:31 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 12:11:31 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 12:11:31 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 12:11:31 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 12:11:33 INFO - Downloading blessings-1.6.tar.gz 12:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:33 INFO - Installing collected packages: blessings 12:11:33 INFO - Running setup.py install for blessings 12:11:33 INFO - Successfully installed blessings 12:11:33 INFO - Cleaning up... 12:11:33 INFO - Return code: 0 12:11:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:34 INFO - Reading from file tmpfile_stdout 12:11:34 INFO - Current package versions: 12:11:34 INFO - blessings == 1.6 12:11:34 INFO - blobuploader == 1.2.4 12:11:34 INFO - docopt == 0.6.1 12:11:34 INFO - functools32 == 3.2.3-2 12:11:34 INFO - jsonschema == 2.5.1 12:11:34 INFO - manifestparser == 1.1 12:11:34 INFO - mozInstall == 1.12 12:11:34 INFO - mozcrash == 0.17 12:11:34 INFO - mozdebug == 0.1 12:11:34 INFO - mozdevice == 0.48 12:11:34 INFO - mozfile == 1.2 12:11:34 INFO - mozhttpd == 0.7 12:11:34 INFO - mozinfo == 0.9 12:11:34 INFO - mozleak == 0.1 12:11:34 INFO - mozlog == 3.3 12:11:34 INFO - moznetwork == 0.27 12:11:34 INFO - mozprocess == 0.23 12:11:34 INFO - mozprofile == 0.28 12:11:34 INFO - mozrunner == 6.12 12:11:34 INFO - mozscreenshot == 0.1 12:11:34 INFO - mozsystemmonitor == 0.3 12:11:34 INFO - moztest == 0.7 12:11:34 INFO - mozversion == 1.4 12:11:34 INFO - psutil == 3.1.1 12:11:34 INFO - requests == 1.2.3 12:11:34 INFO - wsgiref == 0.1.2 12:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:34 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 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:34 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 12:11:34 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 12:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:34 INFO - 'HOME': '/Users/cltbld', 12:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:34 INFO - 'LOGNAME': 'cltbld', 12:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:34 INFO - 'MOZ_NO_REMOTE': '1', 12:11:34 INFO - 'NO_EM_RESTART': '1', 12:11:34 INFO - 'PAGER': '/bin/cat', 12:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:34 INFO - 'PWD': '/builds/slave/test', 12:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:34 INFO - 'SHELL': '/bin/bash', 12:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:34 INFO - 'USER': 'cltbld', 12:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:34 INFO - 'XPC_FLAGS': '0x0', 12:11:34 INFO - 'XPC_SERVICE_NAME': '0', 12:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 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)) 12:11:34 INFO - Cleaning up... 12:11:34 INFO - Return code: 0 12:11:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109e05a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad73430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, '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.87QC4REG1a/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.M9CVlWEPA4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:34 INFO - 'HOME': '/Users/cltbld', 12:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:34 INFO - 'LOGNAME': 'cltbld', 12:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:34 INFO - 'MOZ_NO_REMOTE': '1', 12:11:34 INFO - 'NO_EM_RESTART': '1', 12:11:34 INFO - 'PAGER': '/bin/cat', 12:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:34 INFO - 'PWD': '/builds/slave/test', 12:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:34 INFO - 'SHELL': '/bin/bash', 12:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:11:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:34 INFO - 'USER': 'cltbld', 12:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:34 INFO - 'XPC_FLAGS': '0x0', 12:11:34 INFO - 'XPC_SERVICE_NAME': '0', 12:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:34 INFO - Cleaning up... 12:11:34 INFO - Return code: 0 12:11:34 INFO - Running post-action listener: _resource_record_post_action 12:11:34 INFO - Running post-action listener: _start_resource_monitoring 12:11:34 INFO - Starting resource monitoring. 12:11:34 INFO - [mozharness: 2016-08-29 19:11:34.756598Z] Finished create-virtualenv step (success) 12:11:34 INFO - [mozharness: 2016-08-29 19:11:34.757633Z] Running install step. 12:11:34 INFO - Running pre-action listener: _resource_record_pre_action 12:11:34 INFO - Running main action method: install 12:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:35 INFO - Reading from file tmpfile_stdout 12:11:35 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:35 INFO - Reading from file tmpfile_stdout 12:11:35 INFO - Output received: 12:11:35 INFO - Usage: mozinstall [options] installer 12:11:35 INFO - Options: 12:11:35 INFO - -h, --help show this help message and exit 12:11:35 INFO - -d DEST, --destination=DEST 12:11:35 INFO - Directory to install application into. [default: 12:11:35 INFO - "/builds/slave/test"] 12:11:35 INFO - --app=APP Application being installed. [default: firefox] 12:11:35 INFO - mkdir: /builds/slave/test/build/application 12:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:12:08 INFO - Reading from file tmpfile_stdout 12:12:08 INFO - Output received: 12:12:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:08 INFO - Running post-action listener: _resource_record_post_action 12:12:08 INFO - [mozharness: 2016-08-29 19:12:08.888932Z] Finished install step (success) 12:12:08 INFO - [mozharness: 2016-08-29 19:12:08.889092Z] Running run-tests step. 12:12:08 INFO - Running pre-action listener: _resource_record_pre_action 12:12:08 INFO - Running main action method: run_tests 12:12:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:08 INFO - 2016-08-29 12:12:08.903 screenresolution[1923:19733] starting screenresolution argv=screenresolution get 12:12:08 INFO - 2016-08-29 12:12:08.906 screenresolution[1923:19733] Display 0: 1600x1200x32@60 12:12:08 INFO - 2016-08-29 12:12:08.912 screenresolution[1924:19735] starting screenresolution argv=screenresolution list 12:12:08 INFO - Available Modes on Display 0 12:12:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:09 INFO - Intel Iris: 12:12:09 INFO - Chipset Model: Intel Iris 12:12:09 INFO - Type: GPU 12:12:09 INFO - Bus: Built-In 12:12:09 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:09 INFO - Vendor: Intel (0x8086) 12:12:09 INFO - Device ID: 0x0a2e 12:12:09 INFO - Revision ID: 0x0009 12:12:09 INFO - Displays: 12:12:09 INFO - Display: 12:12:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:09 INFO - Display Serial Number: 1600x1200 60 12:12:09 INFO - Main Display: Yes 12:12:09 INFO - Mirror: Off 12:12:09 INFO - Online: Yes 12:12:09 INFO - Rotation: Supported 12:12:09 INFO - Return code: 0 12:12:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:09 INFO - Python 2.7.3 12:12:09 INFO - Return code: 0 12:12:09 INFO - grabbing minidump binary from tooltool 12:12: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')]} 12:12:09 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 0x109d69d30>, '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 0x109acedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d61b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:09 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 12:12:09 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 12:12:09 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 12:12:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:09 INFO - Return code: 0 12:12:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:09 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/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 12:12:09 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/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 12:12:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.87QC4REG1a/Render', 12:12:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:09 INFO - 'HOME': '/Users/cltbld', 12:12:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:09 INFO - 'LOGNAME': 'cltbld', 12:12:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:09 INFO - 'MOZ_NO_REMOTE': '1', 12:12:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:09 INFO - 'NO_EM_RESTART': '1', 12:12:09 INFO - 'PAGER': '/bin/cat', 12:12:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:09 INFO - 'PWD': '/builds/slave/test', 12:12:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:09 INFO - 'SHELL': '/bin/bash', 12:12:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M9CVlWEPA4/Listeners', 12:12:09 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:12:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:09 INFO - 'USER': 'cltbld', 12:12:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:09 INFO - 'XPC_FLAGS': '0x0', 12:12:09 INFO - 'XPC_SERVICE_NAME': '0', 12:12:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:09 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/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 12:12:09 INFO - mozversion application_buildid: 20160829095731 12:12:09 INFO - mozversion application_changeset: 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 12:12:09 INFO - mozversion application_display_name: Nightly 12:12:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:09 INFO - mozversion application_name: Firefox 12:12:09 INFO - mozversion application_remotingname: firefox 12:12:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:12:09 INFO - mozversion application_vendor: Mozilla 12:12:09 INFO - mozversion application_version: 51.0a1 12:12:09 INFO - mozversion platform_buildid: 20160829095731 12:12:09 INFO - mozversion platform_changeset: 2ec2538353807a17b8b23f2440c9a52ec3f3ccc5 12:12:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:12:09 INFO - mozversion platform_version: 51.0a1 12:12:09 INFO - using testdate: 1472497929 12:12:09 INFO - actual date: 1472497929 12:12:09 INFO - starting webserver on 'localhost:53133' 12:12:09 INFO - SUITE-START | Running 2 tests 12:12:09 INFO - TEST-START | dromaeo_css 12:12:09 INFO - operating with platform_type : mac_ 12:12:09 INFO - Initialising browser for dromaeo_css test... 12:12:09 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpk4YZjH/profile http://localhost:53133/getInfo.html) 12:12:11 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 12:12:11 INFO - PROCESS | 1934 | colorDepth:24 12:12:11 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 12:12:11 INFO - PROCESS | 1934 | __metrics 12:12:11 INFO - PROCESS | 1934 | 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" 12:12:11 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:12 INFO - TEST-INFO | 1934: exit 0 12:12:12 INFO - Browser initialized. 12:12:12 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:12 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpk4YZjH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:24 INFO - PROCESS | 1936 | RSS: Main: 234414080 12:12:24 INFO - PROCESS | 1936 | 12:13:16 INFO - PROCESS | 1936 | 1472497996462 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] 12:15:26 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53133/tests/dromaeo/cssquery-ext.html) 12:15:28 INFO - PROCESS | 1936 | RSS: Main: 389386240 12:15:28 INFO - PROCESS | 1936 | 12:18:31 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-ext.html (next: http://localhost:53133/tests/dromaeo/cssquery-jquery.html) 12:18:32 INFO - PROCESS | 1936 | RSS: Main: 367144960 12:18:32 INFO - PROCESS | 1936 | 12:21:34 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53133/tests/dromaeo/cssquery-mootools.html) 12:21:34 INFO - PROCESS | 1936 | RSS: Main: 329396224 12:21:34 INFO - PROCESS | 1936 | 12:24:37 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53133/tests/dromaeo/cssquery-prototype.html) 12:24:37 INFO - PROCESS | 1936 | RSS: Main: 345268224 12:24:37 INFO - PROCESS | 1936 | 12:27:41 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53133/tests/dromaeo/cssquery-yui.html) 12:27:41 INFO - PROCESS | 1936 | RSS: Main: 328826880 12:27:41 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:53133/tests/dromaeo/cssquery-yui.html (next: http://localhost:53133/tests/dromaeo/cssquery-dojo.html) 12:30:44 INFO - PROCESS | 1936 | RSS: Main: 375271424 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | __start_tp_report 12:30:44 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 12:30:44 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 12:30:44 INFO - PROCESS | 1936 | |i|pagename|runs| 12:30:44 INFO - PROCESS | 1936 | |0;dojo.html;2707.62093306937;3285.080177550286;3333.083402064432;3347.564816573845;3363.730901527878;5007.574356179724;5431.646942948715;5678.290213723285;5710.400407957167;5731.369549349568;5697.145428185774;5729.598928075036;5782.797602083928;5796.913046304308;5800.361960184387;5152.665076770017;5193.1171700810955;5210.2445145453885;5257.579393648499;5270.156774916014;5318.388385335678;5354.330708661418;5359.035373632744;5373.301470808789;5435.239066530688;4237.915241695165;5224.8693782655355;5232.92150617741;5261.473852614746;5286.9471305287225;4321.222180007598;5196.818111366122;5221.347331583552;5283.6037297202865;5284.154535274338;5195.480451954812;5202.739863006872;5212.661177023481;5283.207518872162;5302.151655735064;5625.46558076982;5741.483266506035;5790.073588225863;5795.507381872546;5798.449147330997;6109.799438327934;6211.534134939898;6220.377962203788;6259.248890133168;6275.6548389838545;6214.782482613132;6259.655718935457;6273.811785646438;6305.873882522324;6311.589746666452;5702.401247868914;5815.214945982323;5818.447247511522;5826.533877289764;5829.358770535237;6167.198264225622;6188.659623720649;6206.565540412128;6214.906776398358;6268.373162683695;6180.81457556274;6184.783532576338;6188.288346840171;6198;6198.318184999646;6210.347913469021;6256.749730010749;6276.246850377984;6287.402696743802;6295.748170073146;6137.478314343241;6140.780336713847;6145.095020543646;6160.075988601656;6180.227471566054;6266.216722909637;6267.62394256346;6290.24517057956;6291;6295.433410992941;4941.901161976741;4943.357363542788;4951.554360107608;4956.851294461172;4988.6757360771435;4308.418363520947;4341.956580434218;4372.453443319585;4430.401895744098;4469.687121901501;5828.125781132864;5840;5843.970780146072;5850.180974663551;5893.351731309553;6211.16149319845;6247.562670613104;6254.967930291542;6255.249370075529;6275.6862156892885;5554.6944918029085;5580.49775520197;5593.524550413179;5808.157817116495;5812.273465816773;5368.973155134199;5373.570114390839;5426.565874730012;5479.890402191933;5485.232067510552;6353.745850166034;6366.522510811615;6368.395002474757;6388.872222555616;6391.041343798374;6231.53263505248;6239.220097487814;6252.656103914328;6282.465990390868;6284.842878928063;5341.5459685927135;6198.690065496798;6233.75064997395;6269;6359.395857393541;6090.634561926298;6136.938630613637;6164.722587483664;6170.753169873155;6176.073588961692;6220.657863817352;6246.812595622138;6247.3440288769025;6255;6272.686365681789;5981.820545383645;6106.1146133811235;6106.938930610637;6117.755289788359;6134.202553667995;6118.071005858243;6164.568480206522;6167.629942203482;6175.62946223228;6185.319614842274;5984.670843103671;6035.215421995113;6042.305134909366;6067.54493412987;6092.512598992159;5998.790042348497;6080.027195648675;6121.617863244588;6122.4286496988025;6142.938570614416;6031.4270144336215;6034.698265086816;6043.36544662804;6048.848778780566;6080.69596520181 12:30:44 INFO - PROCESS | 1936 | |1;ext.html;487.87315298022514;516.1122868665894;525.8948210357929;531.4738408975113;541.0117817022442;30908.845455772716;31030.06909792707;32439.51340729891;32454.026379208663;32551.348973020555;32647;42306.576934230645;42997.140057198834;43147;45359.31961020587;9659.90340096597;9802.166815820663;9806.11744942955;9809.411435313868;9811.547113221695;9707.70876873695;9820.803583928353;9820.96879827615;9821.803563928754;9822.361546499455;8852.070532594082;11891.464884080195;12115.394230288495;12168.695782605417;12384.133110682256;12159.331236781973;12249.510019599205;12268.938655306667;12275.017998560093;12289.508419663203;8722.215000649972;12021;12104.515819367214;12117.454714537867;12121.212121212093;39674.20651586981;40483.39274910879;40552;42623;42728;3509.508668786372;3566.7872923205973;3959.6040395960463;4045.3122969095325;4087.713860029799;24931.87534062318;30164.698353016407;30796.922107726346;31282.530762038587;31684.524732129037;3225.79032362898;3312.403767321881;3316.8673253069846;3350.4471762159214;3417.4532074867902;451491;452398;452954;453649.17157455656;454804;58731;58904.70547647234;59094.40905590975;59174.70412647909;59249;63238.68380658159;63421.682891585246;63477.682611587574;63579;63614.681926590994;16386.67226655484;16629.916850415913;16657.91723537966;16660.16699165037;16663;12869.48522059126;12934.159279646792;12938;12946.158499697489;12964.22214667123;14757.335919883417;15087.622809429633;15855.445059422866;17014.14929253557;17083.829161708225;5775.422457754191;6699.162604674416;6950.965245173843;6981.89527157093;6984.126984126984;881.7751473374377;921.8755468011733;953.9952300238455;1012.7569383347943;1019.0013786489282;26328.60507092395;28390;28897;29746.1076167711;30278.77778108352;978.9608415663436;983.5672304186136;1043.7808060307268;1047.994760026195;1133.455941148237;15641.530754077168;15786;16047;16171.757423638655;16431.1784410779;609349;613223;616895;621127;621627;58274;58330.12504812431;58388.70805645944;58389;58711.70644146837;1334.3328335832084;1355.5052405871786;1374.271636032904;1374.9381277842722;1378.73803977244;29873.402531948803;30871.84564077165;30899.382012360078;31422.84288578634;31961;50456;50836.237456438925;50863;50868;51032.72418189575;450212.74893625325;450766;451092.70082845696;452538;452562;25250.621240681772;25926;26093.478130436906;26110.86944565265;26120.34699132537;32560.674393255766;39237.80381098076;46369;46410.6076817696;49038;32922;37534.87395378143;38461.80769096137;38726.41910371403;39994;32523.02430927076;37844;38235.42646860244;39032.609673902894;39368.80315598404;35231.82384088063;36116;37034.629653703116;38873.611263886996;38896.22207555889;31294;33258.501122482696;33822.8308858464;33895.66104338925;34218.657813421545;25088.74911250864;25232.62151067699;25316.620250696622;25525.744742552335;25912.74087259179 12:30:44 INFO - PROCESS | 1936 | |2;jquery.html;366.56012784658407;377.5488291491667;381.1671497777356;393.7145569462142;403.3566461493916;18122;18409.44771656851;19108.522286942833;21415.785842141555;21614.567708645816;20112.698309525367;20434.386968390936;20765.792342076576;21024.894875525602;21046.368608941717;5245.475452454743;5833.445822646852;6024.307204671453;6112.969435152818;6114.877702445949;6051.969740151293;6057.969710151465;6086.056661217518;6102.328743838174;6105.114758360035;5724.656520608756;5926.940730592682;5943.583949123563;5982.700864956735;6021.367756385582;5083.288339632454;5343.492368225013;5990.1314309425325;6006.759729610811;6009.1286763419275;4573.748443835588;4633.837815676435;4639.072185562901;4640.489546149921;4683.742394168319;20581.58836823255;24560.385990350205;25353.87323063373;25525.106621268165;25789.871050644626;2014.798520147988;2131.6482780341225;2171.272623671072;2221.944451388712;2292.5300313435705;9803.117719405216;9830.066143716336;9835.606575736962;10143.746406339827;10220.693379198636;1787.5620472984072;1836.5858289117439;1856.4709057918562;1897.3928342930267;1979.425966469722;76763;103804.9239015223;110765;111857.44071279591;118678.50571954223;11116.722081947853;11350.545978160946;11462.713432164262;11470.082393408507;11613.477393517313;12228.694282642828;14125.576232713034;14388.352524136439;14396.856031439762;14398.640033999025;8315.04376996653;8354.248117669438;8491.787705307417;8520.70177543783;8641.222289993932;8432.494050356876;8446.61990210442;8449.915500844912;8613.741587752378;8623.655053797904;3681.337359275356;3701.8149092545805;4346.239408103633;4447.3773671685985;4535.659825513099;1525.9623456049771;1879.6146789908037;1880.1069491991468;1880.7838596000718;1891.0828248299563;753.9208383119803;821.080389963245;942.820864035831;990.1138481059412;1030.2067408095725;19470.415887523395;20875.851828149294;20922.581548368948;21000.264990725253;21128.894355528126;833.6540335760592;870.8998465176459;969.9660511882191;974.8732664753678;986.9932668677908;8653.826923461496;10517.631882884216;11125.944370278097;11127.054200393057;11153.832692509619;557665;561217;565435.4564543513;573247;574806;128143.35928320485;130630.34684826706;132610.67389325984;133159;133351;945.7068308824287;988.2390559269322;989.8020395920701;1001.7545701303081;1032.963846265377;18541.44375668732;18666.440006799818;18717.438476845717;18769.71845422347;18858;52492.68768280671;52601;52764.47235527595;53629.46370536245;54021.10926117567;63225;76238.2269808128;77727.61136194509;79964.20125295587;81353;6953.235144134243;7271.709131634675;7272.7454539093;7745.380369570534;7892.960535197517;12447.377631118588;12545.811812822632;12960.546380876624;13155.01337399718;13243.271620060988;10617.89382106169;11074.667759967213;11252.774944501229;11261.324320540794;11372.090232781193;8828.558572071499;10802;10821.350718956888;11040.889591104307;11137.498812553293;11060.557577696802;11352.378672014114;11596.246243994114;11596.710082248012;11695.005924496172;7091.645417729196;7229.674664639999;7356.632168391452;7388.298111679382;7402.4078073755045;8957.328200385127;10777.676669699922;11268.436578171222;11332.490037948148;11381.317120972768 12:30:44 INFO - PROCESS | 1936 | |3;mootools.html;1364.3996641477756;1538.6614944712167;1559.945401910933;1771.2649250561017;1917.453525745163;7848.215178482156;8618.181272779084;8863.157999990006;8870.822583548324;8952.060033696465;8695.739127826175;8807.11928807119;8808.031116577187;8810.163034511712;8827.07315731847;2334.3113781434477;2360.822938279627;2362.9054837806466;2368.786809187173;2371.4230037025445;2346.1671106757112;2394.940126496843;2400.6159014557675;2412.035185925626;2412.1195763546357;2369.2892132360307;2388.1163969331283;2396.2212281008656;2398.7481314461993;2408.5423769483828;2328.6157783965627;2358.2689366296504;2365.95268094638;2367.183321753992;2382.6187809950497;2343.0979073056824;2368.0882860098814;2377.5126099149634;2379.2862141357523;2385.6540801583674;8695.39132260742;8830.337724670675;8856.20294173521;8933.597988090487;8954.955225223897;5239.449857764956;5447.782088716446;5453.918191227135;5463.508284254396;5465.207544905967;3574.571051473814;3582.731295152848;3639.5268615080136;3648.087978005499;3655.780653160819;1039.9896001039967;1046.7226185060965;1055.7518983038979;1059.2320567588513;1072.1637123044038;11665.183437159401;11732.706682332924;11803.11476639247;11817.586384476503;11822.467988940518;4408.360787685768;4496.348029535699;4636.953630463652;4672.836450724209;4673.95326046742;9267;9274.258059355236;9279.118483744034;9280.675176368795;9282.72151835446;5262.526372626482;5266.34170728659;5273.3935597406025;5278.234655974862;5279;4384.780760961939;4549.294859296814;4739.218029025241;4899.510048995143;4932.309476673268;3679.79761113136;3694.612065733113;3793.070697679086;3828;3855.8843234703004;967.6177909725619;1007.6221416968637;1076.5478499030426;1114.3563244214718;1149.1036991146918;855;873.5675840458887;881.0748713850431;922.958466868979;950.1970834644715;5360.3299587551555;5471.79620483493;5677.914831277534;5982.1325907744;6007.128966299862;429.68418212613705;434.61970775571376;445.7637452150365;447.6150510560915;452.87319550525245;3282.425575524274;3836.3861782115287;3976.1848820991627;3977.707245145339;4079.347304431277;10442;10892.509837057352;10896.019358257636;10933.945330273298;10947.069499092508;10854.185936054828;10866.348019118877;10867.565297388015;10871.891281087246;10877.966593173636;8024.598770061473;8191.139930307232;8228.62971166299;8347.207015333412;8349.290310323691;5747;5829.621074630136;5911.527077833849;5932.851678708067;6001.129836173817;9729.902700973184;10171.23715721309;10229.181665466596;10243.897561024294;10249.898770752126;9967.850482242917;10304.484775761332;10317.535710892878;10415.84376234342;10439.425831579336;6552;6775.322467753185;6960.5475644085;7157.57054576727;7231.891521627285;8292.212239837207;8596.35527335465;8605.741827745178;8606.44058136219;8610.268127209007;7425.517341372793;7450.96274518624;7624.847503049797;7702.998610180641;7717.421193410629;7374.834225772069;7421.628918553943;7458.888116678363;7476.102867655916;7639.770806875803;6766.289539598271;6926.722931082902;6930.826729331807;6940.7917762467205;6941.167059952838;4887.242477416006;4939.012197560574;5007.424146223086;5072.771725272445;5111.539961403491;4938.654294199371;4966.329545511309;5098.923516147187;5178.300929374486;5237.16205407133 12:30:44 INFO - PROCESS | 1936 | |4;prototype.html;293.5655230259216;297.48535034390505;306.153485612282;310.28943718883755;311.6167114449227;5408.513233808956;5408.756605952736;5410.945890541094;5417.241586177934;5485.955294811378;4978.477259887714;5020.447750747424;5067.138586440305;5206.979951628056;5401.918971215425;4003;4032.3951407288846;4034.979825100885;4041.11095558978;4045.554988951213;3476.7392445566556;4004.2191772604356;4038.8788336349808;4053.675705943532;4081.265372233012;3335.34960682667;3856.3606767622045;3992.600739925999;4015.477987861574;4031.9638582515936;3595.220365918874;3862.8860263819083;4001.8199181036925;4005;4012.0772222389014;3475.638074216867;3485.4771784232316;3488.3720930232525;3494.8427320770625;3500.194955160302;4408.977955110204;5127.522994536484;5211.583073354161;5236.816711415082;5237.486006716759;3716.981415092934;3908.4561598168566;3980.4029395590605;4590.357349971006;4621.2837010263465;4433.8448154314765;5587.7485513152005;5611.793464405173;5637;5661.122526008476;3380.7491228245462;3397.405454045532;3495.4931534927546;3633.6729694327637;3858.672012878909;4934.889649828796;5178.169052279674;5315.7873685052555;5774.97112514439;5876;3332.3835090508333;3867.5965150667794;3870.761356365936;3874.5156855393075;3917.434080677161;3968.6031396860085;3977.463042489285;3980.4228386883738;3986.402039694069;3994.560598334181;3539.185987222953;4045.59544045599;4569.29175977724;4588.655850811202;4599.67802253839;5592.804251851166;5597.552195824324;5610.551155907518;5640.379558248589;5710;1260.3131293445094;1284.8137020132028;1322.3057894605408;1341.3427420563853;1342.1544427011115;861.4615865084322;880.5333173418098;909.8544232922701;922.7416323429451;942.1238248429026;5130.871728206825;5170.603936937081;5185.611079169002;5213.869653258623;5249.842504724865;5154.762856914389;5680;5763.711814409263;5792.82621521355;5795.7401310037085;4325.550940434927;4892.315075889378;5201.635885487979;5228.869278268074;5354.866128346823;2651.42994256232;2943.514320137196;2967.629046369204;2994.970050299513;3029.015569939734;4937.839607692189;5524.723763811793;5843.590948633555;5890.528757699374;5899.823005309848;4882.072406242803;5942.801163569507;5945;5952.732127054206;5958.433948774859;5991.700414979234;6000.549958753111;6033.5474839387225;6040.335563088057;6043.637381757108;5047.974760126176;5116.07910576085;5205.76574054176;5373.811916582901;5782.2483077199695;4661.764632372492;5218.8956220876125;5409.432009638931;5703.657780533182;5723.484886360247;5621.329868312519;5995.5803093783015;6039.275286965592;6066.211392518944;6070.605410648293;3174.9523757144125;3179.9523007155376;3742.1580144467275;3760.8307626156343;3785.3943369060817;4854.732989685601;5565.719884426686;5711.543076553782;5736.6558006519745;5815.8772048202945;4698.483166851575;5329.946700532945;5644.576656750843;5658.057809938891;5661.037623604079;5500.944990550203;5514.779408823602;5571;5585.357683866408;5596.244506991504;5189.327496339312;5426.484483974016;5439.401665816677;5459.890802184013;5487.780488780404;4269.40228368035;5601.663900166003;5639.295088113986;5644.008675402043;5685.232493613309;4796;4826.0589185108565;5488.56091512686;5512.39363670004;5521.613487055867 12:30:44 INFO - PROCESS | 1936 | |5;yui.html;715.4455297144714;723.8298999735065;768.8231706707461;772.3049255669899;816.9754907352782;15094.16982065986;15862.920685396586;15876.3649454022;15900.20498975051;15940.84059159408;14219;14326.140431574113;14334.928325358358;14350.425982960669;14766.483173088944;708.0299989015031;724.1165777751136;864.0322838420946;877.2937785083005;899.5816945120517;853.9274161696263;872.3806097670662;873.4279047224078;873.52829472085;879.6261588824756;943.9056094390575;944.117250370902;945.7351941456403;945.8297506448837;949.3117489819895;733.8752412089904;924.5608336040327;938.7230766923774;943.1747221181466;951.3768481644533;709.4678990756933;709.7515869445705;712.8182313510074;712.8431745016088;713.5611598866711;14627.78058329126;14640.04839685417;14655.487057953027;14656.120632762068;14686;13656.24890631015;13796.448142074305;13799.379027943767;13840.093580348139;13901.455128942054;12687.746245075139;12807.167534110253;12819.807702884464;12839.1012629116;13073.280969546671;4466.3747075409465;4495.617872480843;4543.522930092358;4574.9542504574865;4579.175748365293;107267;109685;109706;111999.44000279947;112961;8727.650893964297;10883.727906802234;11057.170712196457;11190.216684832147;11365.886341136647;21912.013959371867;21923.561528769336;21941.451463713536;21985.340439786512;21999.670004949938;7770.9631763216275;7884.605769711607;7916.208379162038;7973.521588704696;8054.798630034296;4355.586219309161;4404.955950440519;4415.690901636919;4551.249043907718;4586.4266966629175;3485.4946032825096;3619.4208926571623;3620.25784714133;3626.0028492164865;3629.328574213779;655.8983357579583;667.4760313154151;673.562184580027;675.3921470676332;678.8133263352519;523.4817530644634;601.9518438524907;625.3496363781616;635.9745610175634;663.0849427789619;7844.21557844211;8998.17470146184;9036.8644470333;9113.635454581743;9194.586243619053;585.5725320515962;587.1867463562911;627.8807026664919;628.5757113948067;635.3329004545209;3981.5819338969573;4317.611414972667;4326.783660816946;4376.759278239728;4430.933535996963;101647;128396;159312;160525.19737401238;161563.19218404067;108049;121463.39268303601;125481;130133;141735.2913235427;82933;105879.99999999846;117966.8203317973;118899;120071.39964300122;8145.226203510657;8464.153584641486;8535.487870727777;8735.43219690718;8743.606537705691;54161;64800;66098.66950665216;66238.33761662322;67955;51390;65221;93750.53124734333;98832.50583747035;102494;10703.625373111903;10707.571697132027;11083.778324433628;11131.276467029616;11281.71795705114;11705.590304339306;11994.64016079519;12046.397680115784;12215.144939854481;12394.318312492538;9308.627654893999;11350.489227984926;11451.59919402817;11481.94259028733;11535.942320288344;11271.492782824957;11351.14866385008;11369.31784092957;11402.144839137263;11571.074314054687;10781.245312828027;10856.891431085904;11037;11040.392778397265;11440.427978601203;9355.485448300136;9467.857982130412;9486.193673537826;9611.375260608318;9680.75798105053;9097.77255568616;9437.103475170123;9495.66765163216;9525.419851504028;9535.189508891823 12:30:44 INFO - PROCESS | 1936 | __end_tp_report 12:30:44 INFO - PROCESS | 1936 | __start_cc_report 12:30:44 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,1498 12:30:44 INFO - PROCESS | 1936 | __end_cc_report 12:30:44 INFO - PROCESS | 1936 | __startTimestamp1472499044867__endTimestamp 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | ------- Summary: start ------- 12:30:44 INFO - PROCESS | 1936 | Number of tests: 6 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5746.51 Median:6042.31 stddev:676.77 (11.2%) stddev-sans-first:635.48 12:30:44 INFO - PROCESS | 1936 | Values: 2707.6 3285.1 3333.1 3347.6 3363.7 5007.6 5431.6 5678.3 5710.4 5731.4 5697.1 5729.6 5782.8 5796.9 5800.4 5152.7 5193.1 5210.2 5257.6 5270.2 5318.4 5354.3 5359.0 5373.3 5435.2 4237.9 5224.9 5232.9 5261.5 5286.9 4321.2 5196.8 5221.3 5283.6 5284.2 5195.5 5202.7 5212.7 5283.2 5302.2 5625.5 5741.5 5790.1 5795.5 5798.4 6109.8 6211.5 6220.4 6259.2 6275.7 6214.8 6259.7 6273.8 6305.9 6311.6 5702.4 5815.2 5818.4 5826.5 5829.4 6167.2 6188.7 6206.6 6214.9 6268.4 6180.8 6184.8 6188.3 6198.0 6198.3 6210.3 6256.7 6276.2 6287.4 6295.7 6137.5 6140.8 6145.1 6160.1 6180.2 6266.2 6267.6 6290.2 6291.0 6295.4 4941.9 4943.4 4951.6 4956.9 4988.7 4308.4 4342.0 4372.5 4430.4 4469.7 5828.1 5840.0 5844.0 5850.2 5893.4 6211.2 6247.6 6255.0 6255.2 6275.7 5554.7 5580.5 5593.5 5808.2 5812.3 5369.0 5373.6 5426.6 5479.9 5485.2 6353.7 6366.5 6368.4 6388.9 6391.0 6231.5 6239.2 6252.7 6282.5 6284.8 5341.5 6198.7 6233.8 6269.0 6359.4 6090.6 6136.9 6164.7 6170.8 6176.1 6220.7 6246.8 6247.3 6255.0 6272.7 5981.8 6106.1 6106.9 6117.8 6134.2 6118.1 6164.6 6167.6 6175.6 6185.3 5984.7 6035.2 6042.3 6067.5 6092.5 5998.8 6080.0 6121.6 6122.4 6142.9 6031.4 6034.7 6043.4 6048.8 6080.7 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:64722.02 Median:36575.31 stddev:136705.52 (373.8%) stddev-sans-first:137003.94 12:30:44 INFO - PROCESS | 1936 | Values: 487.9 516.1 525.9 531.5 541.0 30908.8 31030.1 32439.5 32454.0 32551.3 32647.0 42306.6 42997.1 43147.0 45359.3 9659.9 9802.2 9806.1 9809.4 9811.5 9707.7 9820.8 9821.0 9821.8 9822.4 8852.1 11891.5 12115.4 12168.7 12384.1 12159.3 12249.5 12268.9 12275.0 12289.5 8722.2 12021.0 12104.5 12117.5 12121.2 39674.2 40483.4 40552.0 42623.0 42728.0 3509.5 3566.8 3959.6 4045.3 4087.7 24931.9 30164.7 30796.9 31282.5 31684.5 3225.8 3312.4 3316.9 3350.4 3417.5 451491.0 452398.0 452954.0 453649.2 454804.0 58731.0 58904.7 59094.4 59174.7 59249.0 63238.7 63421.7 63477.7 63579.0 63614.7 16386.7 16629.9 16657.9 16660.2 16663.0 12869.5 12934.2 12938.0 12946.2 12964.2 14757.3 15087.6 15855.4 17014.1 17083.8 5775.4 6699.2 6951.0 6981.9 6984.1 881.8 921.9 954.0 1012.8 1019.0 26328.6 28390.0 28897.0 29746.1 30278.8 979.0 983.6 1043.8 1048.0 1133.5 15641.5 15786.0 16047.0 16171.8 16431.2 609349.0 613223.0 616895.0 621127.0 621627.0 58274.0 58330.1 58388.7 58389.0 58711.7 1334.3 1355.5 1374.3 1374.9 1378.7 29873.4 30871.8 30899.4 31422.8 31961.0 50456.0 50836.2 50863.0 50868.0 51032.7 450212.7 450766.0 451092.7 452538.0 452562.0 25250.6 25926.0 26093.5 26110.9 26120.3 32560.7 39237.8 46369.0 46410.6 49038.0 32922.0 37534.9 38461.8 38726.4 39994.0 32523.0 37844.0 38235.4 39032.6 39368.8 35231.8 36116.0 37034.6 38873.6 38896.2 31294.0 33258.5 33822.8 33895.7 34218.7 25088.7 25232.6 25316.6 25525.7 25912.7 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:33638.64 Median:13078.22 stddev:94697.51 (724.1%) stddev-sans-first:94930.21 12:30:44 INFO - PROCESS | 1936 | Values: 366.6 377.5 381.2 393.7 403.4 18122.0 18409.4 19108.5 21415.8 21614.6 20112.7 20434.4 20765.8 21024.9 21046.4 5245.5 5833.4 6024.3 6113.0 6114.9 6052.0 6058.0 6086.1 6102.3 6105.1 5724.7 5926.9 5943.6 5982.7 6021.4 5083.3 5343.5 5990.1 6006.8 6009.1 4573.7 4633.8 4639.1 4640.5 4683.7 20581.6 24560.4 25353.9 25525.1 25789.9 2014.8 2131.6 2171.3 2221.9 2292.5 9803.1 9830.1 9835.6 10143.7 10220.7 1787.6 1836.6 1856.5 1897.4 1979.4 76763.0 103804.9 110765.0 111857.4 118678.5 11116.7 11350.5 11462.7 11470.1 11613.5 12228.7 14125.6 14388.4 14396.9 14398.6 8315.0 8354.2 8491.8 8520.7 8641.2 8432.5 8446.6 8449.9 8613.7 8623.7 3681.3 3701.8 4346.2 4447.4 4535.7 1526.0 1879.6 1880.1 1880.8 1891.1 753.9 821.1 942.8 990.1 1030.2 19470.4 20875.9 20922.6 21000.3 21128.9 833.7 870.9 970.0 974.9 987.0 8653.8 10517.6 11125.9 11127.1 11153.8 557665.0 561217.0 565435.5 573247.0 574806.0 128143.4 130630.3 132610.7 133159.0 133351.0 945.7 988.2 989.8 1001.8 1033.0 18541.4 18666.4 18717.4 18769.7 18858.0 52492.7 52601.0 52764.5 53629.5 54021.1 63225.0 76238.2 77727.6 79964.2 81353.0 6953.2 7271.7 7272.7 7745.4 7893.0 12447.4 12545.8 12960.5 13155.0 13243.3 10617.9 11074.7 11252.8 11261.3 11372.1 8828.6 10802.0 10821.4 11040.9 11137.5 11060.6 11352.4 11596.2 11596.7 11695.0 7091.6 7229.7 7356.6 7388.3 7402.4 8957.3 10777.7 11268.4 11332.5 11381.3 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:5691.91 Median:4893.38 stddev:3243.85 (66.3%) stddev-sans-first:3236.64 12:30:44 INFO - PROCESS | 1936 | Values: 1364.4 1538.7 1559.9 1771.3 1917.5 7848.2 8618.2 8863.2 8870.8 8952.1 8695.7 8807.1 8808.0 8810.2 8827.1 2334.3 2360.8 2362.9 2368.8 2371.4 2346.2 2394.9 2400.6 2412.0 2412.1 2369.3 2388.1 2396.2 2398.7 2408.5 2328.6 2358.3 2366.0 2367.2 2382.6 2343.1 2368.1 2377.5 2379.3 2385.7 8695.4 8830.3 8856.2 8933.6 8955.0 5239.4 5447.8 5453.9 5463.5 5465.2 3574.6 3582.7 3639.5 3648.1 3655.8 1040.0 1046.7 1055.8 1059.2 1072.2 11665.2 11732.7 11803.1 11817.6 11822.5 4408.4 4496.3 4637.0 4672.8 4674.0 9267.0 9274.3 9279.1 9280.7 9282.7 5262.5 5266.3 5273.4 5278.2 5279.0 4384.8 4549.3 4739.2 4899.5 4932.3 3679.8 3694.6 3793.1 3828.0 3855.9 967.6 1007.6 1076.5 1114.4 1149.1 855.0 873.6 881.1 923.0 950.2 5360.3 5471.8 5677.9 5982.1 6007.1 429.7 434.6 445.8 447.6 452.9 3282.4 3836.4 3976.2 3977.7 4079.3 10442.0 10892.5 10896.0 10933.9 10947.1 10854.2 10866.3 10867.6 10871.9 10878.0 8024.6 8191.1 8228.6 8347.2 8349.3 5747.0 5829.6 5911.5 5932.9 6001.1 9729.9 10171.2 10229.2 10243.9 10249.9 9967.9 10304.5 10317.5 10415.8 10439.4 6552.0 6775.3 6960.5 7157.6 7231.9 8292.2 8596.4 8605.7 8606.4 8610.3 7425.5 7451.0 7624.8 7703.0 7717.4 7374.8 7421.6 7458.9 7476.1 7639.8 6766.3 6926.7 6930.8 6940.8 6941.2 4887.2 4939.0 5007.4 5072.8 5111.5 4938.7 4966.3 5098.9 5178.3 5237.2 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:4506.48 Median:5162.68 stddev:1409.54 (27.3%) stddev-sans-first:1377.57 12:30:44 INFO - PROCESS | 1936 | Values: 293.6 297.5 306.2 310.3 311.6 5408.5 5408.8 5410.9 5417.2 5486.0 4978.5 5020.4 5067.1 5207.0 5401.9 4003.0 4032.4 4035.0 4041.1 4045.6 3476.7 4004.2 4038.9 4053.7 4081.3 3335.3 3856.4 3992.6 4015.5 4032.0 3595.2 3862.9 4001.8 4005.0 4012.1 3475.6 3485.5 3488.4 3494.8 3500.2 4409.0 5127.5 5211.6 5236.8 5237.5 3717.0 3908.5 3980.4 4590.4 4621.3 4433.8 5587.7 5611.8 5637.0 5661.1 3380.7 3397.4 3495.5 3633.7 3858.7 4934.9 5178.2 5315.8 5775.0 5876.0 3332.4 3867.6 3870.8 3874.5 3917.4 3968.6 3977.5 3980.4 3986.4 3994.6 3539.2 4045.6 4569.3 4588.7 4599.7 5592.8 5597.6 5610.6 5640.4 5710.0 1260.3 1284.8 1322.3 1341.3 1342.2 861.5 880.5 909.9 922.7 942.1 5130.9 5170.6 5185.6 5213.9 5249.8 5154.8 5680.0 5763.7 5792.8 5795.7 4325.6 4892.3 5201.6 5228.9 5354.9 2651.4 2943.5 2967.6 2995.0 3029.0 4937.8 5524.7 5843.6 5890.5 5899.8 4882.1 5942.8 5945.0 5952.7 5958.4 5991.7 6000.5 6033.5 6040.3 6043.6 5048.0 5116.1 5205.8 5373.8 5782.2 4661.8 5218.9 5409.4 5703.7 5723.5 5621.3 5995.6 6039.3 6066.2 6070.6 3175.0 3180.0 3742.2 3760.8 3785.4 4854.7 5565.7 5711.5 5736.7 5815.9 4698.5 5329.9 5644.6 5658.1 5661.0 5500.9 5514.8 5571.0 5585.4 5596.2 5189.3 5426.5 5439.4 5459.9 5487.8 4269.4 5601.7 5639.3 5644.0 5685.2 4796.0 4826.1 5488.6 5512.4 5521.6 12:30:44 INFO - PROCESS | 1936 | 12:30:44 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:23940.18 Median:4448.65 stddev:38957.16 (875.7%) stddev-sans-first:39027.42 12:30:44 INFO - PROCESS | 1936 | Values: 715.4 723.8 768.8 772.3 817.0 15094.2 15862.9 15876.4 15900.2 15940.8 14219.0 14326.1 14334.9 14350.4 14766.5 708.0 724.1 864.0 877.3 899.6 853.9 872.4 873.4 873.5 879.6 943.9 944.1 945.7 945.8 949.3 733.9 924.6 938.7 943.2 951.4 709.5 709.8 712.8 712.8 713.6 14627.8 14640.0 14655.5 14656.1 14686.0 13656.2 13796.4 13799.4 13840.1 13901.5 12687.7 12807.2 12819.8 12839.1 13073.3 4466.4 4495.6 4543.5 4575.0 4579.2 107267.0 109685.0 109706.0 111999.4 112961.0 8727.7 10883.7 11057.2 11190.2 11365.9 21912.0 21923.6 21941.5 21985.3 21999.7 7771.0 7884.6 7916.2 7973.5 8054.8 4355.6 4405.0 4415.7 4551.2 4586.4 3485.5 3619.4 3620.3 3626.0 3629.3 655.9 667.5 673.6 675.4 678.8 523.5 602.0 625.3 636.0 663.1 7844.2 8998.2 9036.9 9113.6 9194.6 585.6 587.2 627.9 628.6 635.3 3981.6 4317.6 4326.8 4376.8 4430.9 101647.0 128396.0 159312.0 160525.2 161563.2 108049.0 121463.4 125481.0 130133.0 141735.3 82933.0 105880.0 117966.8 118899.0 120071.4 8145.2 8464.2 8535.5 8735.4 8743.6 54161.0 64800.0 66098.7 66238.3 67955.0 51390.0 65221.0 93750.5 98832.5 102494.0 10703.6 10707.6 11083.8 11131.3 11281.7 11705.6 11994.6 12046.4 12215.1 12394.3 9308.6 11350.5 11451.6 11481.9 11535.9 11271.5 11351.1 11369.3 11402.1 11571.1 10781.2 10856.9 11037.0 11040.4 11440.4 9355.5 9467.9 9486.2 9611.4 9680.8 9097.8 9437.1 9495.7 9525.4 9535.2 12:30:44 INFO - PROCESS | 1936 | -------- Summary: end -------- 12:30:44 INFO - PROCESS | 1936 | 12:30:45 INFO - TEST-INFO | 1936: exit 0 12:30:45 INFO - TEST-OK | dromaeo_css | took 1116002ms 12:30:45 INFO - TEST-START | kraken 12:30:45 INFO - operating with platform_type : mac_ 12:30:45 INFO - Initialising browser for kraken test... 12:30:45 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpXvSezs/profile http://localhost:53133/getInfo.html) 12:30:47 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 12:30:47 INFO - PROCESS | 1940 | colorDepth:24 12:30:47 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 12:30:47 INFO - PROCESS | 1940 | __metrics 12:30:47 INFO - PROCESS | 1940 | 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" 12:30:47 INFO - PROCESS | 1940 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:48 INFO - TEST-INFO | 1940: exit 0 12:30:48 INFO - Browser initialized. 12:30:48 INFO - Running cycle 1/1 for kraken test... 12:30:48 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpXvSezs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:00 INFO - PROCESS | 1941 | RSS: Main: 234565632 12:31:00 INFO - PROCESS | 1941 | 12:31:03 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53133/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:04 INFO - PROCESS | 1941 | RSS: Main: 292646912 12:31:04 INFO - PROCESS | 1941 | 12:31:06 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53133/tests/kraken/driver.html?testName=audio-dft) 12:31:07 INFO - PROCESS | 1941 | RSS: Main: 288313344 12:31:07 INFO - PROCESS | 1941 | 12:31:10 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53133/tests/kraken/driver.html?testName=audio-fft) 12:31:11 INFO - PROCESS | 1941 | RSS: Main: 378847232 12:31:11 INFO - PROCESS | 1941 | 12:31:13 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53133/tests/kraken/driver.html?testName=audio-oscillator) 12:31:14 INFO - PROCESS | 1941 | RSS: Main: 282128384 12:31:14 INFO - PROCESS | 1941 | 12:31:16 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53133/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:16 INFO - PROCESS | 1941 | RSS: Main: 284786688 12:31:16 INFO - PROCESS | 1941 | 12:31:19 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53133/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:20 INFO - PROCESS | 1941 | RSS: Main: 321966080 12:31:20 INFO - PROCESS | 1941 | 12:31:23 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53133/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:23 INFO - PROCESS | 1941 | RSS: Main: 323641344 12:31:23 INFO - PROCESS | 1941 | 12:31:26 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53133/tests/kraken/driver.html?testName=json-parse-financial) 12:31:27 INFO - PROCESS | 1941 | RSS: Main: 322740224 12:31:27 INFO - PROCESS | 1941 | 12:31:28 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53133/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:29 INFO - PROCESS | 1941 | RSS: Main: 299040768 12:31:29 INFO - PROCESS | 1941 | 12:31:31 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:31 INFO - PROCESS | 1941 | RSS: Main: 304390144 12:31:31 INFO - PROCESS | 1941 | 12:31:34 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:34 INFO - PROCESS | 1941 | RSS: Main: 304345088 12:31:34 INFO - PROCESS | 1941 | 12:31:37 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:38 INFO - PROCESS | 1941 | RSS: Main: 320860160 12:31:38 INFO - PROCESS | 1941 | 12:31:41 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:41 INFO - PROCESS | 1941 | RSS: Main: 316928000 12:31:41 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53133/tests/kraken/driver.html?testName=ai-astar) 12:31:43 INFO - PROCESS | 1941 | RSS: Main: 360427520 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | __start_tp_report 12:31:43 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 12:31:43 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 12:31:43 INFO - PROCESS | 1941 | |i|pagename|runs| 12:31:43 INFO - PROCESS | 1941 | |0;ai-astar;114;113;133;125;165;139;115;156;168;113 12:31:43 INFO - PROCESS | 1941 | |1;audio-beat-detection;147;114;129;160;109;133;130;109;164;105 12:31:43 INFO - PROCESS | 1941 | |2;audio-dft;186;186;186;184;184;182;229;183;182;184 12:31:43 INFO - PROCESS | 1941 | |3;audio-fft;79;73;76;151;58;57;58;67;140;57 12:31:43 INFO - PROCESS | 1941 | |4;audio-oscillator;82;84;133;79;98;122;131;69;69;119 12:31:43 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;87;84;87;85;87;83;88;85;88;84 12:31:43 INFO - PROCESS | 1941 | |6;imaging-darkroom;93;93;93;97;92;93;93;96;93;98 12:31:43 INFO - PROCESS | 1941 | |7;imaging-desaturate;87;87;88;94;123;85;92;86;84;88 12:31:43 INFO - PROCESS | 1941 | |8;json-parse-financial;55;55;55;52;54;53;54;53;52;55 12:31:43 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;66;68;65;78;73;77;73;70;72;72 12:31:43 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;89;86;84;84;85;87;85;88;90;86 12:31:43 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;151;152;137;138;135;140;139;138;177;141 12:31:43 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;164;170;168;166;168;175;166;168;168;166 12:31:43 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;66;67;66;67;64;66;61;59;79;87 12:31:43 INFO - PROCESS | 1941 | __end_tp_report 12:31:43 INFO - PROCESS | 1941 | __start_cc_report 12:31:43 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,2058 12:31:43 INFO - PROCESS | 1941 | __end_cc_report 12:31:43 INFO - PROCESS | 1941 | __startTimestamp1472499103780__endTimestamp 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | ------- Summary: start ------- 12:31:43 INFO - PROCESS | 1941 | Number of tests: 14 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:134.10 Median:136.00 stddev:21.99 (16.2%) stddev-sans-first:22.09 12:31:43 INFO - PROCESS | 1941 | Values: 114.0 113.0 133.0 125.0 165.0 139.0 115.0 156.0 168.0 113.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:131.50 stddev:21.39 (16.3%) stddev-sans-first:21.79 12:31:43 INFO - PROCESS | 1941 | Values: 147.0 114.0 129.0 160.0 109.0 133.0 130.0 109.0 164.0 105.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:188.60 Median:185.00 stddev:14.28 (7.7%) stddev-sans-first:15.11 12:31:43 INFO - PROCESS | 1941 | Values: 186.0 186.0 186.0 184.0 184.0 182.0 229.0 183.0 182.0 184.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:81.60 Median:62.50 stddev:34.76 (55.6%) stddev-sans-first:36.86 12:31:43 INFO - PROCESS | 1941 | Values: 79.0 73.0 76.0 151.0 58.0 57.0 58.0 67.0 140.0 57.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:74.00 stddev:25.43 (34.4%) stddev-sans-first:26.26 12:31:43 INFO - PROCESS | 1941 | Values: 82.0 84.0 133.0 79.0 98.0 122.0 131.0 69.0 69.0 119.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 12:31:43 INFO - PROCESS | 1941 | Values: 87.0 84.0 87.0 85.0 87.0 83.0 88.0 85.0 88.0 84.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.00 stddev:2.08 (2.2%) stddev-sans-first:2.17 12:31:43 INFO - PROCESS | 1941 | Values: 93.0 93.0 93.0 97.0 92.0 93.0 93.0 96.0 93.0 98.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:87.50 stddev:11.51 (13.2%) stddev-sans-first:12.10 12:31:43 INFO - PROCESS | 1941 | Values: 87.0 87.0 88.0 94.0 123.0 85.0 92.0 86.0 84.0 88.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.50 stddev:1.23 (2.3%) stddev-sans-first:1.22 12:31:43 INFO - PROCESS | 1941 | Values: 55.0 55.0 55.0 52.0 54.0 53.0 54.0 53.0 52.0 55.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.50 stddev:4.27 (5.9%) stddev-sans-first:4.06 12:31:43 INFO - PROCESS | 1941 | Values: 66.0 68.0 65.0 78.0 73.0 77.0 73.0 70.0 72.0 72.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.50 stddev:2.07 (2.4%) stddev-sans-first:1.96 12:31:43 INFO - PROCESS | 1941 | Values: 89.0 86.0 84.0 84.0 85.0 87.0 85.0 88.0 90.0 86.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:140.50 stddev:12.68 (9.0%) stddev-sans-first:13.25 12:31:43 INFO - PROCESS | 1941 | Values: 151.0 152.0 137.0 138.0 135.0 140.0 139.0 138.0 177.0 141.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:3.00 (1.8%) stddev-sans-first:2.83 12:31:43 INFO - PROCESS | 1941 | Values: 164.0 170.0 168.0 166.0 168.0 175.0 166.0 168.0 168.0 166.0 12:31:43 INFO - PROCESS | 1941 | 12:31:43 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:66.50 stddev:8.44 (12.7%) stddev-sans-first:8.92 12:31:43 INFO - PROCESS | 1941 | Values: 66.0 67.0 66.0 67.0 64.0 66.0 61.0 59.0 79.0 87.0 12:31:43 INFO - PROCESS | 1941 | -------- Summary: end -------- 12:31:43 INFO - PROCESS | 1941 | 12:31:44 INFO - TEST-INFO | 1941: exit 0 12:31:44 INFO - TEST-OK | kraken | took 59143ms 12:31:44 INFO - SUITE-END | took 1175s 12:31:44 INFO - Completed test suite (00:19:35) 12:31:44 INFO - javascript benchmark 12:31:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2707.62093306937, 3285.080177550286, 3333.083402064432, 3347.564816573845, 3363.730901527878, 5007.574356179724, 5431.646942948715, 5678.290213723285, 5710.400407957167, 5731.369549349568, 5697.145428185774, 5729.598928075036, 5782.797602083928, 5796.913046304308, 5800.361960184387, 5152.665076770017, 5193.1171700810955, 5210.2445145453885, 5257.579393648499, 5270.156774916014, 5318.388385335678, 5354.330708661418, 5359.035373632744, 5373.301470808789, 5435.239066530688, 4237.915241695165, 5224.8693782655355, 5232.92150617741, 5261.473852614746, 5286.9471305287225, 4321.222180007598, 5196.818111366122, 5221.347331583552, 5283.6037297202865, 5284.154535274338, 5195.480451954812, 5202.739863006872, 5212.661177023481, 5283.207518872162, 5302.151655735064, 5625.46558076982, 5741.483266506035, 5790.073588225863, 5795.507381872546, 5798.449147330997, 6109.799438327934, 6211.534134939898, 6220.377962203788, 6259.248890133168, 6275.6548389838545, 6214.782482613132, 6259.655718935457, 6273.811785646438, 6305.873882522324, 6311.589746666452, 5702.401247868914, 5815.214945982323, 5818.447247511522, 5826.533877289764, 5829.358770535237, 6167.198264225622, 6188.659623720649, 6206.565540412128, 6214.906776398358, 6268.373162683695, 6180.81457556274, 6184.783532576338, 6188.288346840171, 6198.0, 6198.318184999646, 6210.347913469021, 6256.749730010749, 6276.246850377984, 6287.402696743802, 6295.748170073146, 6137.478314343241, 6140.780336713847, 6145.095020543646, 6160.075988601656, 6180.227471566054, 6266.216722909637, 6267.62394256346, 6290.24517057956, 6291.0, 6295.433410992941, 4941.901161976741, 4943.357363542788, 4951.554360107608, 4956.851294461172, 4988.6757360771435, 4308.418363520947, 4341.956580434218, 4372.453443319585, 4430.401895744098, 4469.687121901501, 5828.125781132864, 5840.0, 5843.970780146072, 5850.180974663551, 5893.351731309553, 6211.16149319845, 6247.562670613104, 6254.967930291542, 6255.249370075529, 6275.6862156892885, 5554.6944918029085, 5580.49775520197, 5593.524550413179, 5808.157817116495, 5812.273465816773, 5368.973155134199, 5373.570114390839, 5426.565874730012, 5479.890402191933, 5485.232067510552, 6353.745850166034, 6366.522510811615, 6368.395002474757, 6388.872222555616, 6391.041343798374, 6231.53263505248, 6239.220097487814, 6252.656103914328, 6282.465990390868, 6284.842878928063, 5341.5459685927135, 6198.690065496798, 6233.75064997395, 6269.0, 6359.395857393541, 6090.634561926298, 6136.938630613637, 6164.722587483664, 6170.753169873155, 6176.073588961692, 6220.657863817352, 6246.812595622138, 6247.3440288769025, 6255.0, 6272.686365681789, 5981.820545383645, 6106.1146133811235, 6106.938930610637, 6117.755289788359, 6134.202553667995, 6118.071005858243, 6164.568480206522, 6167.629942203482, 6175.62946223228, 6185.319614842274, 5984.670843103671, 6035.215421995113, 6042.305134909366, 6067.54493412987, 6092.512598992159, 5998.790042348497, 6080.027195648675, 6121.617863244588, 6122.4286496988025, 6142.938570614416, 6031.4270144336215, 6034.698265086816, 6043.36544662804, 6048.848778780566, 6080.69596520181], "value": 5700.201714837928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.87315298022514, 516.1122868665894, 525.8948210357929, 531.4738408975113, 541.0117817022442, 30908.845455772716, 31030.06909792707, 32439.51340729891, 32454.026379208663, 32551.348973020555, 32647.0, 42306.576934230645, 42997.140057198834, 43147.0, 45359.31961020587, 9659.90340096597, 9802.166815820663, 9806.11744942955, 9809.411435313868, 9811.547113221695, 9707.70876873695, 9820.803583928353, 9820.96879827615, 9821.803563928754, 9822.361546499455, 8852.070532594082, 11891.464884080195, 12115.394230288495, 12168.695782605417, 12384.133110682256, 12159.331236781973, 12249.510019599205, 12268.938655306667, 12275.017998560093, 12289.508419663203, 8722.215000649972, 12021.0, 12104.515819367214, 12117.454714537867, 12121.212121212093, 39674.20651586981, 40483.39274910879, 40552.0, 42623.0, 42728.0, 3509.508668786372, 3566.7872923205973, 3959.6040395960463, 4045.3122969095325, 4087.713860029799, 24931.87534062318, 30164.698353016407, 30796.922107726346, 31282.530762038587, 31684.524732129037, 3225.79032362898, 3312.403767321881, 3316.8673253069846, 3350.4471762159214, 3417.4532074867902, 451491.0, 452398.0, 452954.0, 453649.17157455656, 454804.0, 58731.0, 58904.70547647234, 59094.40905590975, 59174.70412647909, 59249.0, 63238.68380658159, 63421.682891585246, 63477.682611587574, 63579.0, 63614.681926590994, 16386.67226655484, 16629.916850415913, 16657.91723537966, 16660.16699165037, 16663.0, 12869.48522059126, 12934.159279646792, 12938.0, 12946.158499697489, 12964.22214667123, 14757.335919883417, 15087.622809429633, 15855.445059422866, 17014.14929253557, 17083.829161708225, 5775.422457754191, 6699.162604674416, 6950.965245173843, 6981.89527157093, 6984.126984126984, 881.7751473374377, 921.8755468011733, 953.9952300238455, 1012.7569383347943, 1019.0013786489282, 26328.60507092395, 28390.0, 28897.0, 29746.1076167711, 30278.77778108352, 978.9608415663436, 983.5672304186136, 1043.7808060307268, 1047.994760026195, 1133.455941148237, 15641.530754077168, 15786.0, 16047.0, 16171.757423638655, 16431.1784410779, 609349.0, 613223.0, 616895.0, 621127.0, 621627.0, 58274.0, 58330.12504812431, 58388.70805645944, 58389.0, 58711.70644146837, 1334.3328335832084, 1355.5052405871786, 1374.271636032904, 1374.9381277842722, 1378.73803977244, 29873.402531948803, 30871.84564077165, 30899.382012360078, 31422.84288578634, 31961.0, 50456.0, 50836.237456438925, 50863.0, 50868.0, 51032.72418189575, 450212.74893625325, 450766.0, 451092.70082845696, 452538.0, 452562.0, 25250.621240681772, 25926.0, 26093.478130436906, 26110.86944565265, 26120.34699132537, 32560.674393255766, 39237.80381098076, 46369.0, 46410.6076817696, 49038.0, 32922.0, 37534.87395378143, 38461.80769096137, 38726.41910371403, 39994.0, 32523.02430927076, 37844.0, 38235.42646860244, 39032.609673902894, 39368.80315598404, 35231.82384088063, 36116.0, 37034.629653703116, 38873.611263886996, 38896.22207555889, 31294.0, 33258.501122482696, 33822.8308858464, 33895.66104338925, 34218.657813421545, 25088.74911250864, 25232.62151067699, 25316.620250696622, 25525.744742552335, 25912.74087259179], "value": 19826.874157065427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.56012784658407, 377.5488291491667, 381.1671497777356, 393.7145569462142, 403.3566461493916, 18122.0, 18409.44771656851, 19108.522286942833, 21415.785842141555, 21614.567708645816, 20112.698309525367, 20434.386968390936, 20765.792342076576, 21024.894875525602, 21046.368608941717, 5245.475452454743, 5833.445822646852, 6024.307204671453, 6112.969435152818, 6114.877702445949, 6051.969740151293, 6057.969710151465, 6086.056661217518, 6102.328743838174, 6105.114758360035, 5724.656520608756, 5926.940730592682, 5943.583949123563, 5982.700864956735, 6021.367756385582, 5083.288339632454, 5343.492368225013, 5990.1314309425325, 6006.759729610811, 6009.1286763419275, 4573.748443835588, 4633.837815676435, 4639.072185562901, 4640.489546149921, 4683.742394168319, 20581.58836823255, 24560.385990350205, 25353.87323063373, 25525.106621268165, 25789.871050644626, 2014.798520147988, 2131.6482780341225, 2171.272623671072, 2221.944451388712, 2292.5300313435705, 9803.117719405216, 9830.066143716336, 9835.606575736962, 10143.746406339827, 10220.693379198636, 1787.5620472984072, 1836.5858289117439, 1856.4709057918562, 1897.3928342930267, 1979.425966469722, 76763.0, 103804.9239015223, 110765.0, 111857.44071279591, 118678.50571954223, 11116.722081947853, 11350.545978160946, 11462.713432164262, 11470.082393408507, 11613.477393517313, 12228.694282642828, 14125.576232713034, 14388.352524136439, 14396.856031439762, 14398.640033999025, 8315.04376996653, 8354.248117669438, 8491.787705307417, 8520.70177543783, 8641.222289993932, 8432.494050356876, 8446.61990210442, 8449.915500844912, 8613.741587752378, 8623.655053797904, 3681.337359275356, 3701.8149092545805, 4346.239408103633, 4447.3773671685985, 4535.659825513099, 1525.9623456049771, 1879.6146789908037, 1880.1069491991468, 1880.7838596000718, 1891.0828248299563, 753.9208383119803, 821.080389963245, 942.820864035831, 990.1138481059412, 1030.2067408095725, 19470.415887523395, 20875.851828149294, 20922.581548368948, 21000.264990725253, 21128.894355528126, 833.6540335760592, 870.8998465176459, 969.9660511882191, 974.8732664753678, 986.9932668677908, 8653.826923461496, 10517.631882884216, 11125.944370278097, 11127.054200393057, 11153.832692509619, 557665.0, 561217.0, 565435.4564543513, 573247.0, 574806.0, 128143.35928320485, 130630.34684826706, 132610.67389325984, 133159.0, 133351.0, 945.7068308824287, 988.2390559269322, 989.8020395920701, 1001.7545701303081, 1032.963846265377, 18541.44375668732, 18666.440006799818, 18717.438476845717, 18769.71845422347, 18858.0, 52492.68768280671, 52601.0, 52764.47235527595, 53629.46370536245, 54021.10926117567, 63225.0, 76238.2269808128, 77727.61136194509, 79964.20125295587, 81353.0, 6953.235144134243, 7271.709131634675, 7272.7454539093, 7745.380369570534, 7892.960535197517, 12447.377631118588, 12545.811812822632, 12960.546380876624, 13155.01337399718, 13243.271620060988, 10617.89382106169, 11074.667759967213, 11252.774944501229, 11261.324320540794, 11372.090232781193, 8828.558572071499, 10802.0, 10821.350718956888, 11040.889591104307, 11137.498812553293, 11060.557577696802, 11352.378672014114, 11596.246243994114, 11596.710082248012, 11695.005924496172, 7091.645417729196, 7229.674664639999, 7356.632168391452, 7388.298111679382, 7402.4078073755045, 8957.328200385127, 10777.676669699922, 11268.436578171222, 11332.490037948148, 11381.317120972768], "value": 9319.542685670809, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.3996641477756, 1538.6614944712167, 1559.945401910933, 1771.2649250561017, 1917.453525745163, 7848.215178482156, 8618.181272779084, 8863.157999990006, 8870.822583548324, 8952.060033696465, 8695.739127826175, 8807.11928807119, 8808.031116577187, 8810.163034511712, 8827.07315731847, 2334.3113781434477, 2360.822938279627, 2362.9054837806466, 2368.786809187173, 2371.4230037025445, 2346.1671106757112, 2394.940126496843, 2400.6159014557675, 2412.035185925626, 2412.1195763546357, 2369.2892132360307, 2388.1163969331283, 2396.2212281008656, 2398.7481314461993, 2408.5423769483828, 2328.6157783965627, 2358.2689366296504, 2365.95268094638, 2367.183321753992, 2382.6187809950497, 2343.0979073056824, 2368.0882860098814, 2377.5126099149634, 2379.2862141357523, 2385.6540801583674, 8695.39132260742, 8830.337724670675, 8856.20294173521, 8933.597988090487, 8954.955225223897, 5239.449857764956, 5447.782088716446, 5453.918191227135, 5463.508284254396, 5465.207544905967, 3574.571051473814, 3582.731295152848, 3639.5268615080136, 3648.087978005499, 3655.780653160819, 1039.9896001039967, 1046.7226185060965, 1055.7518983038979, 1059.2320567588513, 1072.1637123044038, 11665.183437159401, 11732.706682332924, 11803.11476639247, 11817.586384476503, 11822.467988940518, 4408.360787685768, 4496.348029535699, 4636.953630463652, 4672.836450724209, 4673.95326046742, 9267.0, 9274.258059355236, 9279.118483744034, 9280.675176368795, 9282.72151835446, 5262.526372626482, 5266.34170728659, 5273.3935597406025, 5278.234655974862, 5279.0, 4384.780760961939, 4549.294859296814, 4739.218029025241, 4899.510048995143, 4932.309476673268, 3679.79761113136, 3694.612065733113, 3793.070697679086, 3828.0, 3855.8843234703004, 967.6177909725619, 1007.6221416968637, 1076.5478499030426, 1114.3563244214718, 1149.1036991146918, 855.0, 873.5675840458887, 881.0748713850431, 922.958466868979, 950.1970834644715, 5360.3299587551555, 5471.79620483493, 5677.914831277534, 5982.1325907744, 6007.128966299862, 429.68418212613705, 434.61970775571376, 445.7637452150365, 447.6150510560915, 452.87319550525245, 3282.425575524274, 3836.3861782115287, 3976.1848820991627, 3977.707245145339, 4079.347304431277, 10442.0, 10892.509837057352, 10896.019358257636, 10933.945330273298, 10947.069499092508, 10854.185936054828, 10866.348019118877, 10867.565297388015, 10871.891281087246, 10877.966593173636, 8024.598770061473, 8191.139930307232, 8228.62971166299, 8347.207015333412, 8349.290310323691, 5747.0, 5829.621074630136, 5911.527077833849, 5932.851678708067, 6001.129836173817, 9729.902700973184, 10171.23715721309, 10229.181665466596, 10243.897561024294, 10249.898770752126, 9967.850482242917, 10304.484775761332, 10317.535710892878, 10415.84376234342, 10439.425831579336, 6552.0, 6775.322467753185, 6960.5475644085, 7157.57054576727, 7231.891521627285, 8292.212239837207, 8596.35527335465, 8605.741827745178, 8606.44058136219, 8610.268127209007, 7425.517341372793, 7450.96274518624, 7624.847503049797, 7702.998610180641, 7717.421193410629, 7374.834225772069, 7421.628918553943, 7458.888116678363, 7476.102867655916, 7639.770806875803, 6766.289539598271, 6926.722931082902, 6930.826729331807, 6940.7917762467205, 6941.167059952838, 4887.242477416006, 4939.012197560574, 5007.424146223086, 5072.771725272445, 5111.539961403491, 4938.654294199371, 4966.329545511309, 5098.923516147187, 5178.300929374486, 5237.16205407133], "value": 4470.231375703193, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.5655230259216, 297.48535034390505, 306.153485612282, 310.28943718883755, 311.6167114449227, 5408.513233808956, 5408.756605952736, 5410.945890541094, 5417.241586177934, 5485.955294811378, 4978.477259887714, 5020.447750747424, 5067.138586440305, 5206.979951628056, 5401.918971215425, 4003.0, 4032.3951407288846, 4034.979825100885, 4041.11095558978, 4045.554988951213, 3476.7392445566556, 4004.2191772604356, 4038.8788336349808, 4053.675705943532, 4081.265372233012, 3335.34960682667, 3856.3606767622045, 3992.600739925999, 4015.477987861574, 4031.9638582515936, 3595.220365918874, 3862.8860263819083, 4001.8199181036925, 4005.0, 4012.0772222389014, 3475.638074216867, 3485.4771784232316, 3488.3720930232525, 3494.8427320770625, 3500.194955160302, 4408.977955110204, 5127.522994536484, 5211.583073354161, 5236.816711415082, 5237.486006716759, 3716.981415092934, 3908.4561598168566, 3980.4029395590605, 4590.357349971006, 4621.2837010263465, 4433.8448154314765, 5587.7485513152005, 5611.793464405173, 5637.0, 5661.122526008476, 3380.7491228245462, 3397.405454045532, 3495.4931534927546, 3633.6729694327637, 3858.672012878909, 4934.889649828796, 5178.169052279674, 5315.7873685052555, 5774.97112514439, 5876.0, 3332.3835090508333, 3867.5965150667794, 3870.761356365936, 3874.5156855393075, 3917.434080677161, 3968.6031396860085, 3977.463042489285, 3980.4228386883738, 3986.402039694069, 3994.560598334181, 3539.185987222953, 4045.59544045599, 4569.29175977724, 4588.655850811202, 4599.67802253839, 5592.804251851166, 5597.552195824324, 5610.551155907518, 5640.379558248589, 5710.0, 1260.3131293445094, 1284.8137020132028, 1322.3057894605408, 1341.3427420563853, 1342.1544427011115, 861.4615865084322, 880.5333173418098, 909.8544232922701, 922.7416323429451, 942.1238248429026, 5130.871728206825, 5170.603936937081, 5185.611079169002, 5213.869653258623, 5249.842504724865, 5154.762856914389, 5680.0, 5763.711814409263, 5792.82621521355, 5795.7401310037085, 4325.550940434927, 4892.315075889378, 5201.635885487979, 5228.869278268074, 5354.866128346823, 2651.42994256232, 2943.514320137196, 2967.629046369204, 2994.970050299513, 3029.015569939734, 4937.839607692189, 5524.723763811793, 5843.590948633555, 5890.528757699374, 5899.823005309848, 4882.072406242803, 5942.801163569507, 5945.0, 5952.732127054206, 5958.433948774859, 5991.700414979234, 6000.549958753111, 6033.5474839387225, 6040.335563088057, 6043.637381757108, 5047.974760126176, 5116.07910576085, 5205.76574054176, 5373.811916582901, 5782.2483077199695, 4661.764632372492, 5218.8956220876125, 5409.432009638931, 5703.657780533182, 5723.484886360247, 5621.329868312519, 5995.5803093783015, 6039.275286965592, 6066.211392518944, 6070.605410648293, 3174.9523757144125, 3179.9523007155376, 3742.1580144467275, 3760.8307626156343, 3785.3943369060817, 4854.732989685601, 5565.719884426686, 5711.543076553782, 5736.6558006519745, 5815.8772048202945, 4698.483166851575, 5329.946700532945, 5644.576656750843, 5658.057809938891, 5661.037623604079, 5500.944990550203, 5514.779408823602, 5571.0, 5585.357683866408, 5596.244506991504, 5189.327496339312, 5426.484483974016, 5439.401665816677, 5459.890802184013, 5487.780488780404, 4269.40228368035, 5601.663900166003, 5639.295088113986, 5644.008675402043, 5685.232493613309, 4796.0, 4826.0589185108565, 5488.56091512686, 5512.39363670004, 5521.613487055867], "value": 4060.8614048730537, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4455297144714, 723.8298999735065, 768.8231706707461, 772.3049255669899, 816.9754907352782, 15094.16982065986, 15862.920685396586, 15876.3649454022, 15900.20498975051, 15940.84059159408, 14219.0, 14326.140431574113, 14334.928325358358, 14350.425982960669, 14766.483173088944, 708.0299989015031, 724.1165777751136, 864.0322838420946, 877.2937785083005, 899.5816945120517, 853.9274161696263, 872.3806097670662, 873.4279047224078, 873.52829472085, 879.6261588824756, 943.9056094390575, 944.117250370902, 945.7351941456403, 945.8297506448837, 949.3117489819895, 733.8752412089904, 924.5608336040327, 938.7230766923774, 943.1747221181466, 951.3768481644533, 709.4678990756933, 709.7515869445705, 712.8182313510074, 712.8431745016088, 713.5611598866711, 14627.78058329126, 14640.04839685417, 14655.487057953027, 14656.120632762068, 14686.0, 13656.24890631015, 13796.448142074305, 13799.379027943767, 13840.093580348139, 13901.455128942054, 12687.746245075139, 12807.167534110253, 12819.807702884464, 12839.1012629116, 13073.280969546671, 4466.3747075409465, 4495.617872480843, 4543.522930092358, 4574.9542504574865, 4579.175748365293, 107267.0, 109685.0, 109706.0, 111999.44000279947, 112961.0, 8727.650893964297, 10883.727906802234, 11057.170712196457, 11190.216684832147, 11365.886341136647, 21912.013959371867, 21923.561528769336, 21941.451463713536, 21985.340439786512, 21999.670004949938, 7770.9631763216275, 7884.605769711607, 7916.208379162038, 7973.521588704696, 8054.798630034296, 4355.586219309161, 4404.955950440519, 4415.690901636919, 4551.249043907718, 4586.4266966629175, 3485.4946032825096, 3619.4208926571623, 3620.25784714133, 3626.0028492164865, 3629.328574213779, 655.8983357579583, 667.4760313154151, 673.562184580027, 675.3921470676332, 678.8133263352519, 523.4817530644634, 601.9518438524907, 625.3496363781616, 635.9745610175634, 663.0849427789619, 7844.21557844211, 8998.17470146184, 9036.8644470333, 9113.635454581743, 9194.586243619053, 585.5725320515962, 587.1867463562911, 627.8807026664919, 628.5757113948067, 635.3329004545209, 3981.5819338969573, 4317.611414972667, 4326.783660816946, 4376.759278239728, 4430.933535996963, 101647.0, 128396.0, 159312.0, 160525.19737401238, 161563.19218404067, 108049.0, 121463.39268303601, 125481.0, 130133.0, 141735.2913235427, 82933.0, 105879.99999999846, 117966.8203317973, 118899.0, 120071.39964300122, 8145.226203510657, 8464.153584641486, 8535.487870727777, 8735.43219690718, 8743.606537705691, 54161.0, 64800.0, 66098.66950665216, 66238.33761662322, 67955.0, 51390.0, 65221.0, 93750.53124734333, 98832.50583747035, 102494.0, 10703.625373111903, 10707.571697132027, 11083.778324433628, 11131.276467029616, 11281.71795705114, 11705.590304339306, 11994.64016079519, 12046.397680115784, 12215.144939854481, 12394.318312492538, 9308.627654893999, 11350.489227984926, 11451.59919402817, 11481.94259028733, 11535.942320288344, 11271.492782824957, 11351.14866385008, 11369.31784092957, 11402.144839137263, 11571.074314054687, 10781.245312828027, 10856.891431085904, 11037.0, 11040.392778397265, 11440.427978601203, 9355.485448300136, 9467.857982130412, 9486.193673537826, 9611.375260608318, 9680.75798105053, 9097.77255568616, 9437.103475170123, 9495.66765163216, 9525.419851504028, 9535.189508891823], "value": 7543.890746495943, "unit": "score"}], "name": "dromaeo_css", "value": 7241.938987333673, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 133.0, 125.0, 165.0, 139.0, 115.0, 156.0, 168.0, 113.0], "value": 134.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 129.0, 160.0, 109.0, 133.0, 130.0, 109.0, 164.0, 105.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 184.0, 184.0, 182.0, 229.0, 183.0, 182.0, 184.0], "value": 188.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 76.0, 151.0, 58.0, 57.0, 58.0, 67.0, 140.0, 57.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 133.0, 79.0, 98.0, 122.0, 131.0, 69.0, 69.0, 119.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 87.0, 83.0, 88.0, 85.0, 88.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 97.0, 92.0, 93.0, 93.0, 96.0, 93.0, 98.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 94.0, 123.0, 85.0, 92.0, 86.0, 84.0, 88.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 52.0, 54.0, 53.0, 54.0, 53.0, 52.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 78.0, 73.0, 77.0, 73.0, 70.0, 72.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 84.0, 84.0, 85.0, 87.0, 85.0, 88.0, 90.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 137.0, 138.0, 135.0, 140.0, 139.0, 138.0, 177.0, 141.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 168.0, 166.0, 168.0, 175.0, 166.0, 168.0, 168.0, 166.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 64.0, 66.0, 61.0, 59.0, 79.0, 87.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1496.7000000000003, "alertThreshold": 2.0}]} 12:31:45 INFO - Return code: 0 12:31:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:31:45 INFO - # TBPL SUCCESS # 12:31:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:45 INFO - 2016-08-29 12:31:45.091 screenresolution[1945:21908] starting screenresolution argv=screenresolution get 12:31:45 INFO - 2016-08-29 12:31:45.094 screenresolution[1945:21908] Display 0: 1600x1200x32@60 12:31:45 INFO - 2016-08-29 12:31:45.100 screenresolution[1946:21911] starting screenresolution argv=screenresolution list 12:31:45 INFO - Available Modes on Display 0 12:31:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:45 INFO - Intel Iris: 12:31:45 INFO - Chipset Model: Intel Iris 12:31:45 INFO - Type: GPU 12:31:45 INFO - Bus: Built-In 12:31:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:45 INFO - Vendor: Intel (0x8086) 12:31:45 INFO - Device ID: 0x0a2e 12:31:45 INFO - Revision ID: 0x0009 12:31:45 INFO - Displays: 12:31:45 INFO - Display: 12:31:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:45 INFO - Display Serial Number: 1600x1200 60 12:31:45 INFO - Main Display: Yes 12:31:45 INFO - Mirror: Off 12:31:45 INFO - Online: Yes 12:31:45 INFO - Rotation: Supported 12:31:45 INFO - Return code: 0 12:31:45 INFO - Running post-action listener: _resource_record_post_action 12:31:45 INFO - [mozharness: 2016-08-29 19:31:45.347973Z] Finished run-tests step (success) 12:31:45 INFO - Running post-run listener: _resource_record_post_run 12:31:45 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 1335296; Write bytes: 706883584; Read time: 15; Write time: 2089 12:31:45 INFO - TinderboxPrint: CPU usage
53.6% 12:31:45 INFO - TinderboxPrint: I/O read bytes / time
1,335,296 / 15 12:31:45 INFO - TinderboxPrint: I/O write bytes / time
706,883,584 / 2,089 12:31:45 INFO - TinderboxPrint: CPU idle
2,248.6 (46.4%) 12:31:45 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 12:31:45 INFO - TinderboxPrint: CPU user
1,624.7 (33.6%) 12:31:45 INFO - TinderboxPrint: Swap in / out
407,318,528 / 0 12:31:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406761984; Write bytes: 409397248; Read time: 29785; Write time: 750 12:31:45 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1261568; Write bytes: 295872512; Read time: 13; Write time: 1332 12:31:45 INFO - Running post-run listener: _upload_blobber_files 12:31:45 INFO - Blob upload gear active. 12:31:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:31:45 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 12:31:45 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'] 12:31:45 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 12:31:46 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:31:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:46 INFO - (blobuploader) - INFO - Done attempting. 12:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:31:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:47 INFO - (blobuploader) - INFO - Done attempting. 12:31:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:50 INFO - (blobuploader) - INFO - Done attempting. 12:31:50 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:50 INFO - Return code: 0 12:31:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/786deb3eb51c66db1ae32bd96c40b3d9cf25a210cdf11c70cc48e864a8ab8ca77d4051b833eda85eabee34aebba868b1fb052f9576120c0ee07719ac1dc7ad63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fa2dbe8f63b0a01a48cfa39e3e728aa1419696624e0b8723997f32eb8e7dabb7a30ab5ee3e27e721fcf978d45811eeb6e280db4c21379e4979b897c70ba29ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0918bd0f772c64c98f71ca24a97ecb85c410459430c1f15ead61fa1161dda81bb2920e232573fbc439f589d3377fdb3735da53d06c72cdc3d4eefd9309c777c6"} 12:31:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:50 INFO - Contents: 12:31:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/786deb3eb51c66db1ae32bd96c40b3d9cf25a210cdf11c70cc48e864a8ab8ca77d4051b833eda85eabee34aebba868b1fb052f9576120c0ee07719ac1dc7ad63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fa2dbe8f63b0a01a48cfa39e3e728aa1419696624e0b8723997f32eb8e7dabb7a30ab5ee3e27e721fcf978d45811eeb6e280db4c21379e4979b897c70ba29ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0918bd0f772c64c98f71ca24a97ecb85c410459430c1f15ead61fa1161dda81bb2920e232573fbc439f589d3377fdb3735da53d06c72cdc3d4eefd9309c777c6"} 12:31:50 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:50 INFO - Copying logs to upload dir... 12:31:50 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.931861 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-29 12:31:50.399229) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 12:31:50.402812) ========= 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.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/786deb3eb51c66db1ae32bd96c40b3d9cf25a210cdf11c70cc48e864a8ab8ca77d4051b833eda85eabee34aebba868b1fb052f9576120c0ee07719ac1dc7ad63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fa2dbe8f63b0a01a48cfa39e3e728aa1419696624e0b8723997f32eb8e7dabb7a30ab5ee3e27e721fcf978d45811eeb6e280db4c21379e4979b897c70ba29ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0918bd0f772c64c98f71ca24a97ecb85c410459430c1f15ead61fa1161dda81bb2920e232573fbc439f589d3377fdb3735da53d06c72cdc3d4eefd9309c777c6"} build_url:https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/G7yKT5Z7QaqUGJgbELGpzw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/786deb3eb51c66db1ae32bd96c40b3d9cf25a210cdf11c70cc48e864a8ab8ca77d4051b833eda85eabee34aebba868b1fb052f9576120c0ee07719ac1dc7ad63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fa2dbe8f63b0a01a48cfa39e3e728aa1419696624e0b8723997f32eb8e7dabb7a30ab5ee3e27e721fcf978d45811eeb6e280db4c21379e4979b897c70ba29ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0918bd0f772c64c98f71ca24a97ecb85c410459430c1f15ead61fa1161dda81bb2920e232573fbc439f589d3377fdb3735da53d06c72cdc3d4eefd9309c777c6"}' ========= master_lag: 0.70 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 12:31:51.117482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:31:51.117825) ========= 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.87QC4REG1a/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.M9CVlWEPA4/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005184 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:31:51.148304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 12:31:51.148654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 12:31:51.149069) ========= ========= Total master_lag: 0.85 =========