builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1479154974.89 results: success (0) buildid: 20161114091743 builduid: 42ed84f7fcb64e2fb957c8f0e52037f0 revision: fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:54.889292) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:54.889759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:54.890039) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:54.904733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:54.905092) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 12:22:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.3M=0s 2016-11-14 12:22:55 (69.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138936 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.058861) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.059193) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018288 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.109628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.109988) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 12:22:55,419 truncating revision to first 12 chars 2016-11-14 12:22:55,419 Setting DEBUG logging. 2016-11-14 12:22:55,419 attempt 1/10 2016-11-14 12:22:55,419 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fd3c6c15ec64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 12:22:55,716 unpacking tar archive at: mozilla-beta-fd3c6c15ec64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.637604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.762643) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.763027) ========= script_repo_revision: fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.763519) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.763825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 12:22:55.772056) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-14 12:22:55.772432) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:56 INFO - MultiFileLogger online at 20161114 12:22:56 in /builds/slave/test 12:22:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:22:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:56 INFO - {'append_to_log': False, 12:22:56 INFO - 'base_work_dir': '/builds/slave/test', 12:22:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:22:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:56 INFO - 'branch': 'Mozilla-Beta', 12:22:56 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:56 INFO - 'config_files': ('talos/linux_config.py',), 12:22:56 INFO - 'default_actions': ('clobber', 12:22:56 INFO - 'read-buildbot-config', 12:22:56 INFO - 'download-and-extract', 12:22:56 INFO - 'populate-webroot', 12:22:56 INFO - 'create-virtualenv', 12:22:56 INFO - 'install', 12:22:56 INFO - 'run-tests'), 12:22:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:56 INFO - 'download_minidump_stackwalk': True, 12:22:56 INFO - 'download_symbols': 'ondemand', 12:22:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:56 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:56 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:56 INFO - 'installer_path': 'installer.exe', 12:22:56 INFO - 'log_level': 'info', 12:22:56 INFO - 'log_name': 'talos', 12:22:56 INFO - 'log_to_console': True, 12:22:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:56 INFO - 'opt_config_files': (), 12:22:56 INFO - 'pip_index': False, 12:22:56 INFO - 'sps_profile': False, 12:22:56 INFO - 'sps_profile_interval': 0, 12:22:56 INFO - 'suite': 'dromaeojs', 12:22:56 INFO - 'system_bits': '32', 12:22:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:56 INFO - 'title': 'talos-linux64-ix-062', 12:22:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:56 INFO - 'use_talos_json': True, 12:22:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:56 INFO - 'work_dir': 'build'} 12:22:56 INFO - [mozharness: 2016-11-14 20:22:56.169344Z] Running clobber step. 12:22:56 INFO - Running pre-action listener: _resource_record_pre_action 12:22:56 INFO - Running main action method: clobber 12:22:56 INFO - rmtree: /builds/slave/test/build 12:22:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:57 INFO - Running post-action listener: _resource_record_post_action 12:22:57 INFO - [mozharness: 2016-11-14 20:22:57.133997Z] Finished clobber step (success) 12:22:57 INFO - [mozharness: 2016-11-14 20:22:57.134096Z] Running read-buildbot-config step. 12:22:57 INFO - Running pre-action listener: _resource_record_pre_action 12:22:57 INFO - Running main action method: read_buildbot_config 12:22:57 INFO - Using buildbot properties: 12:22:57 INFO - { 12:22:57 INFO - "project": "", 12:22:57 INFO - "product": "firefox", 12:22:57 INFO - "script_repo_revision": "production", 12:22:57 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:22:57 INFO - "repository": "", 12:22:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:22:57 INFO - "buildid": "20161114091743", 12:22:57 INFO - "pgo_build": "True", 12:22:57 INFO - "basedir": "/builds/slave/test", 12:22:57 INFO - "buildnumber": 464, 12:22:57 INFO - "slavename": "talos-linux64-ix-062", 12:22:57 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:22:57 INFO - "platform": "ubuntu64_hw", 12:22:57 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:22:57 INFO - "branch": "mozilla-beta", 12:22:57 INFO - "repo_path": "releases/mozilla-beta", 12:22:57 INFO - "revision": "fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1", 12:22:57 INFO - "stage_platform": "linux64-pgo", 12:22:57 INFO - "builduid": "42ed84f7fcb64e2fb957c8f0e52037f0", 12:22:57 INFO - "slavebuilddir": "test" 12:22:57 INFO - } 12:22:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 12:22:57 INFO - Running post-action listener: _resource_record_post_action 12:22:57 INFO - [mozharness: 2016-11-14 20:22:57.136582Z] Finished read-buildbot-config step (success) 12:22:57 INFO - [mozharness: 2016-11-14 20:22:57.136671Z] Running download-and-extract step. 12:22:57 INFO - Running pre-action listener: _resource_record_pre_action 12:22:57 INFO - Running main action method: download_and_extract 12:22:57 INFO - mkdir: /builds/slave/test/build/tests 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:57 INFO - trying https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 12:22:57 INFO - Downloading https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 12:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:22:57 INFO - Downloaded 1327 bytes. 12:22:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 12:22:57 INFO - Using the following test package requirements: 12:22:57 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 12:22:57 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:22:57 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 12:22:57 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'jsshell-linux-x86_64.zip'], 12:22:57 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:22:57 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 12:22:57 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 12:22:57 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 12:22:57 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:22:57 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:22:57 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:22:57 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:22:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 12:22:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:22:57 INFO - Fetch https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 12:22:58 INFO - Expected file size: 21962426 12:23:02 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:23:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 12:23:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:23:02 INFO - Fetch https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 12:23:03 INFO - Expected file size: 21962426 12:23:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 12:23:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:23:07 INFO - Fetch https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 12:23:08 INFO - Expected file size: 14414089 12:23: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:23:08 INFO - trying https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:23:08 INFO - Downloading https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:23:10 INFO - Downloaded 56978142 bytes. 12:23:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:23:10 INFO - mkdir: /builds/slave/test/properties 12:23:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:10 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:10 INFO - Contents: 12:23:10 INFO - build_url:https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:23:11 INFO - Running post-action listener: _resource_record_post_action 12:23:11 INFO - Running post-action listener: set_extra_try_arguments 12:23:11 INFO - [mozharness: 2016-11-14 20:23:11.000904Z] Finished download-and-extract step (success) 12:23:11 INFO - [mozharness: 2016-11-14 20:23:11.001132Z] Running populate-webroot step. 12:23:11 INFO - Running pre-action listener: _resource_record_pre_action 12:23:11 INFO - Running main action method: populate_webroot 12:23:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:11 INFO - '--tppagecycles', 12:23:11 INFO - '7'], 12:23:11 INFO - 'tests': ['tsvgm']}}, 12:23:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['damp', 'tps']}, 12:23:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'tests': ['damp', 'tps']}, 12:23:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['dromaeo_dom']}, 12:23:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['basic_compositor_video']}, 12:23:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['a11yr', 12:23:11 INFO - 'ts_paint', 12:23:11 INFO - 'tpaint', 12:23:11 INFO - 'sessionrestore', 12:23:11 INFO - 'sessionrestore_no_auto_restore', 12:23:11 INFO - 'tabpaint']}, 12:23:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['tsvgx', 12:23:11 INFO - 'tsvgr_opacity', 12:23:11 INFO - 'tart', 12:23:11 INFO - 'tscrollx', 12:23:11 INFO - 'cart']}, 12:23:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:11 INFO - 'tsvgr_opacity', 12:23:11 INFO - 'tart', 12:23:11 INFO - 'tscrollx', 12:23:11 INFO - 'cart']}, 12:23:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'talos_options': ['--disable-e10s'], 12:23:11 INFO - 'tests': ['tp5o']}, 12:23:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'tests': ['tp5o']}, 12:23:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'talos_options': ['--disable-e10s', 12:23:11 INFO - '--xperf_path', 12:23:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:11 INFO - 'tests': ['tp5n']}, 12:23:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:11 INFO - 'talos_options': ['--xperf_path', 12:23:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:11 INFO - 'tests': ['tp5n']}}, 12:23:11 INFO - 'talos.zip': {'path': '', 12:23:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:11 INFO - Running post-action listener: _resource_record_post_action 12:23:11 INFO - [mozharness: 2016-11-14 20:23:11.020332Z] Finished populate-webroot step (success) 12:23:11 INFO - [mozharness: 2016-11-14 20:23:11.020427Z] Running create-virtualenv step. 12:23:11 INFO - Running pre-action listener: _resource_record_pre_action 12:23:11 INFO - Running main action method: create_virtualenv 12:23:11 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:11 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:23:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:11 INFO - Using real prefix '/usr' 12:23:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:11 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:13 INFO - Installing pip.................done. 12:23:13 INFO - Return code: 0 12:23:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:13 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:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:13 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:23:13 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:23:13 INFO - Using env: {'DISPLAY': ':0', 12:23:13 INFO - 'HOME': '/home/cltbld', 12:23:13 INFO - 'LANG': 'en_US.UTF-8', 12:23:13 INFO - 'LANGUAGE': 'en_US:en', 12:23:13 INFO - 'LOGNAME': 'cltbld', 12:23:13 INFO - 'MAIL': '/var/mail/cltbld', 12:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:13 INFO - 'MOZ_NO_REMOTE': '1', 12:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:13 INFO - 'NO_EM_RESTART': '1', 12:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:13 INFO - 'PWD': '/builds/slave/test', 12:23:13 INFO - 'SHELL': '/bin/bash', 12:23:13 INFO - 'SHLVL': '1', 12:23:13 INFO - 'TERM': 'linux', 12:23:13 INFO - 'TMOUT': '86400', 12:23:13 INFO - 'USER': 'cltbld', 12:23:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:13 INFO - '_': '/tools/buildbot/bin/python'} 12:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:13 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:23:13 INFO - Cleaning up... 12:23:13 INFO - Return code: 0 12:23:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23:13 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:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:13 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:13 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:23:13 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:23:13 INFO - Using env: {'DISPLAY': ':0', 12:23:13 INFO - 'HOME': '/home/cltbld', 12:23:13 INFO - 'LANG': 'en_US.UTF-8', 12:23:13 INFO - 'LANGUAGE': 'en_US:en', 12:23:13 INFO - 'LOGNAME': 'cltbld', 12:23:13 INFO - 'MAIL': '/var/mail/cltbld', 12:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:13 INFO - 'MOZ_NO_REMOTE': '1', 12:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:13 INFO - 'NO_EM_RESTART': '1', 12:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:13 INFO - 'PWD': '/builds/slave/test', 12:23:13 INFO - 'SHELL': '/bin/bash', 12:23:13 INFO - 'SHLVL': '1', 12:23:13 INFO - 'TERM': 'linux', 12:23:13 INFO - 'TMOUT': '86400', 12:23:13 INFO - 'USER': 'cltbld', 12:23:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:13 INFO - '_': '/tools/buildbot/bin/python'} 12:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:14 INFO - Downloading/unpacking psutil>=3.1.1 12:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:16 INFO - Installing collected packages: psutil 12:23:16 INFO - Running setup.py install for psutil 12:23:16 INFO - building 'psutil._psutil_linux' extension 12:23:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:23:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:23:16 INFO - building 'psutil._psutil_posix' extension 12:23:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:23:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:23:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:16 INFO - Successfully installed psutil 12:23:16 INFO - Cleaning up... 12:23:16 INFO - Return code: 0 12:23:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:16 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:16 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:23:16 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:23:16 INFO - Using env: {'DISPLAY': ':0', 12:23:16 INFO - 'HOME': '/home/cltbld', 12:23:16 INFO - 'LANG': 'en_US.UTF-8', 12:23:16 INFO - 'LANGUAGE': 'en_US:en', 12:23:16 INFO - 'LOGNAME': 'cltbld', 12:23:16 INFO - 'MAIL': '/var/mail/cltbld', 12:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:16 INFO - 'MOZ_NO_REMOTE': '1', 12:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:16 INFO - 'NO_EM_RESTART': '1', 12:23:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:16 INFO - 'PWD': '/builds/slave/test', 12:23:16 INFO - 'SHELL': '/bin/bash', 12:23:16 INFO - 'SHLVL': '1', 12:23:16 INFO - 'TERM': 'linux', 12:23:16 INFO - 'TMOUT': '86400', 12:23:16 INFO - 'USER': 'cltbld', 12:23:16 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:16 INFO - '_': '/tools/buildbot/bin/python'} 12:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23: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:23: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:23: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:23: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:23: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:23: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:23:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:18 INFO - Installing collected packages: mozsystemmonitor 12:23:18 INFO - Running setup.py install for mozsystemmonitor 12:23:18 INFO - Successfully installed mozsystemmonitor 12:23:18 INFO - Cleaning up... 12:23:18 INFO - Return code: 0 12:23:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23:18 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:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:18 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:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:18 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:18 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:23:18 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:23:18 INFO - Using env: {'DISPLAY': ':0', 12:23:18 INFO - 'HOME': '/home/cltbld', 12:23:18 INFO - 'LANG': 'en_US.UTF-8', 12:23:18 INFO - 'LANGUAGE': 'en_US:en', 12:23:18 INFO - 'LOGNAME': 'cltbld', 12:23:18 INFO - 'MAIL': '/var/mail/cltbld', 12:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:19 INFO - 'MOZ_NO_REMOTE': '1', 12:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:19 INFO - 'NO_EM_RESTART': '1', 12:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:19 INFO - 'PWD': '/builds/slave/test', 12:23:19 INFO - 'SHELL': '/bin/bash', 12:23:19 INFO - 'SHLVL': '1', 12:23:19 INFO - 'TERM': 'linux', 12:23:19 INFO - 'TMOUT': '86400', 12:23:19 INFO - 'USER': 'cltbld', 12:23:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:19 INFO - '_': '/tools/buildbot/bin/python'} 12:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:19 INFO - Downloading/unpacking jsonschema==2.5.1 12:23: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:23: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:23: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:23: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:23: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:23: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:23:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23:21 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:23:21 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:23:21 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:23:21 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:23:21 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:23:21 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:23:21 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:21 INFO - warning: no files found matching '*.txt' 12:23:21 INFO - no previously-included directories found matching 'build' 12:23:21 INFO - no previously-included directories found matching 'dist' 12:23:21 INFO - no previously-included directories found matching '.git*' 12:23:21 INFO - Installing collected packages: jsonschema, functools32 12:23:21 INFO - Running setup.py install for functools32 12:23:21 INFO - warning: no files found matching '*.txt' 12:23:21 INFO - no previously-included directories found matching 'build' 12:23:21 INFO - no previously-included directories found matching 'dist' 12:23:21 INFO - no previously-included directories found matching '.git*' 12:23:21 INFO - Successfully installed jsonschema functools32 12:23:21 INFO - Cleaning up... 12:23:21 INFO - Return code: 0 12:23:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23:21 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:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:21 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:23:21 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:23:21 INFO - Using env: {'DISPLAY': ':0', 12:23:21 INFO - 'HOME': '/home/cltbld', 12:23:21 INFO - 'LANG': 'en_US.UTF-8', 12:23:21 INFO - 'LANGUAGE': 'en_US:en', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MAIL': '/var/mail/cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SHLVL': '1', 12:23:21 INFO - 'TERM': 'linux', 12:23:21 INFO - 'TMOUT': '86400', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - '_': '/tools/buildbot/bin/python'} 12:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:21 INFO - Cleaning up... 12:23:21 INFO - Return code: 0 12:23:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:21 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:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:21 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:23:21 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:23:21 INFO - Using env: {'DISPLAY': ':0', 12:23:21 INFO - 'HOME': '/home/cltbld', 12:23:21 INFO - 'LANG': 'en_US.UTF-8', 12:23:21 INFO - 'LANGUAGE': 'en_US:en', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MAIL': '/var/mail/cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SHLVL': '1', 12:23:21 INFO - 'TERM': 'linux', 12:23:21 INFO - 'TMOUT': '86400', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - '_': '/tools/buildbot/bin/python'} 12:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:22 INFO - Downloading/unpacking blobuploader==1.2.4 12:23: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:23: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:23: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:23: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:23: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:23: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:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 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:23:24 INFO - Downloading docopt-0.6.1.tar.gz 12:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:24 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:24 INFO - Running setup.py install for blobuploader 12:23:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:24 INFO - Running setup.py install for requests 12:23:25 INFO - Running setup.py install for docopt 12:23:25 INFO - Successfully installed blobuploader requests docopt 12:23:25 INFO - Cleaning up... 12:23:25 INFO - Return code: 0 12:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:25 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:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:25 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:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:25 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:25 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:23:25 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:23:25 INFO - Using env: {'DISPLAY': ':0', 12:23:25 INFO - 'HOME': '/home/cltbld', 12:23:25 INFO - 'LANG': 'en_US.UTF-8', 12:23:25 INFO - 'LANGUAGE': 'en_US:en', 12:23:25 INFO - 'LOGNAME': 'cltbld', 12:23:25 INFO - 'MAIL': '/var/mail/cltbld', 12:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:25 INFO - 'MOZ_NO_REMOTE': '1', 12:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:25 INFO - 'NO_EM_RESTART': '1', 12:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:25 INFO - 'PWD': '/builds/slave/test', 12:23:25 INFO - 'SHELL': '/bin/bash', 12:23:25 INFO - 'SHLVL': '1', 12:23:25 INFO - 'TERM': 'linux', 12:23:25 INFO - 'TMOUT': '86400', 12:23:25 INFO - 'USER': 'cltbld', 12:23:25 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:25 INFO - '_': '/tools/buildbot/bin/python'} 12:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:25 INFO - Running setup.py (path:/tmp/pip-PwRkxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:25 INFO - Running setup.py (path:/tmp/pip-0bnuQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:25 INFO - Running setup.py (path:/tmp/pip-TyFJtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:25 INFO - Running setup.py (path:/tmp/pip-Ue7WEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:25 INFO - Running setup.py (path:/tmp/pip-Ik1NSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:25 INFO - Running setup.py (path:/tmp/pip-QJh3Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:26 INFO - Running setup.py (path:/tmp/pip-ebhM39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:26 INFO - Running setup.py (path:/tmp/pip-souHVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:26 INFO - Running setup.py (path:/tmp/pip-rQccCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:26 INFO - Running setup.py (path:/tmp/pip-mBtyWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:26 INFO - Running setup.py (path:/tmp/pip-sT4M9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:26 INFO - Running setup.py (path:/tmp/pip-U9kwkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:26 INFO - Running setup.py (path:/tmp/pip-LThZa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:26 INFO - Running setup.py (path:/tmp/pip-kjUsQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:26 INFO - Running setup.py (path:/tmp/pip-G6eoFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:26 INFO - Running setup.py (path:/tmp/pip-gJbxWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:26 INFO - Running setup.py (path:/tmp/pip-6UxY0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:26 INFO - Running setup.py install for manifestparser 12:23:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for mozcrash 12:23:27 INFO - Running setup.py install for mozdebug 12:23:27 INFO - Running setup.py install for mozdevice 12:23:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for mozfile 12:23:27 INFO - Running setup.py install for mozhttpd 12:23:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for mozinfo 12:23:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for mozInstall 12:23:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for mozleak 12:23:27 INFO - Running setup.py install for mozlog 12:23:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for moznetwork 12:23:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Running setup.py install for mozprocess 12:23:28 INFO - Running setup.py install for mozprofile 12:23:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Running setup.py install for mozrunner 12:23:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Running setup.py install for mozscreenshot 12:23:28 INFO - Running setup.py install for moztest 12:23:28 INFO - Running setup.py install for mozversion 12:23:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:28 INFO - Cleaning up... 12:23:28 INFO - Return code: 0 12:23:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23: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:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23: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:23: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:23:28 INFO - Using env: {'DISPLAY': ':0', 12:23:28 INFO - 'HOME': '/home/cltbld', 12:23:28 INFO - 'LANG': 'en_US.UTF-8', 12:23:28 INFO - 'LANGUAGE': 'en_US:en', 12:23:28 INFO - 'LOGNAME': 'cltbld', 12:23:28 INFO - 'MAIL': '/var/mail/cltbld', 12:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:28 INFO - 'MOZ_NO_REMOTE': '1', 12:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:28 INFO - 'NO_EM_RESTART': '1', 12:23:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:28 INFO - 'PWD': '/builds/slave/test', 12:23:28 INFO - 'SHELL': '/bin/bash', 12:23:28 INFO - 'SHLVL': '1', 12:23:28 INFO - 'TERM': 'linux', 12:23:28 INFO - 'TMOUT': '86400', 12:23:28 INFO - 'USER': 'cltbld', 12:23:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:28 INFO - '_': '/tools/buildbot/bin/python'} 12:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:28 INFO - Running setup.py (path:/tmp/pip-hSTC5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:28 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:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:28 INFO - Running setup.py (path:/tmp/pip-WTFOA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:29 INFO - Running setup.py (path:/tmp/pip-3IPCOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:29 INFO - Running setup.py (path:/tmp/pip-nEgabn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:29 INFO - Running setup.py (path:/tmp/pip-QQvku5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:29 INFO - Running setup.py (path:/tmp/pip-bhFGZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:29 INFO - Running setup.py (path:/tmp/pip-GL4TbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23: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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:29 INFO - Running setup.py (path:/tmp/pip-B49e9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:29 INFO - Running setup.py (path:/tmp/pip-kBjoiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:29 INFO - Running setup.py (path:/tmp/pip-ScOcAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:29 INFO - Running setup.py (path:/tmp/pip-p8v9qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:29 INFO - Running setup.py (path:/tmp/pip-qv6qAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:29 INFO - Running setup.py (path:/tmp/pip-sDULL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:29 INFO - Running setup.py (path:/tmp/pip-AOpqAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:29 INFO - Running setup.py (path:/tmp/pip-A6jwlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:29 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:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:29 INFO - Running setup.py (path:/tmp/pip-YOBuGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23: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:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:30 INFO - Running setup.py (path:/tmp/pip-CWnBdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:30 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:23:30 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:23:30 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:23:30 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:23:30 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:23:30 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:23:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:32 INFO - Downloading blessings-1.6.tar.gz 12:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:32 INFO - Installing collected packages: blessings 12:23:32 INFO - Running setup.py install for blessings 12:23:32 INFO - Successfully installed blessings 12:23:32 INFO - Cleaning up... 12:23:32 INFO - Return code: 0 12:23:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:32 INFO - Reading from file tmpfile_stdout 12:23:32 INFO - Current package versions: 12:23:32 INFO - argparse == 1.2.1 12:23:32 INFO - blessings == 1.6 12:23:32 INFO - blobuploader == 1.2.4 12:23:32 INFO - docopt == 0.6.1 12:23:32 INFO - functools32 == 3.2.3-2 12:23:32 INFO - jsonschema == 2.5.1 12:23:32 INFO - manifestparser == 1.1 12:23:32 INFO - mozInstall == 1.12 12:23:32 INFO - mozcrash == 0.17 12:23:32 INFO - mozdebug == 0.1 12:23:32 INFO - mozdevice == 0.48 12:23:32 INFO - mozfile == 1.2 12:23:32 INFO - mozhttpd == 0.7 12:23:32 INFO - mozinfo == 0.9 12:23:32 INFO - mozleak == 0.1 12:23:32 INFO - mozlog == 3.4 12:23:32 INFO - moznetwork == 0.27 12:23:32 INFO - mozprocess == 0.23 12:23:32 INFO - mozprofile == 0.28 12:23:32 INFO - mozrunner == 6.12 12:23:32 INFO - mozscreenshot == 0.1 12:23:32 INFO - mozsystemmonitor == 0.3 12:23:32 INFO - moztest == 0.7 12:23:32 INFO - mozversion == 1.4 12:23:32 INFO - psutil == 3.1.1 12:23:32 INFO - requests == 1.2.3 12:23:32 INFO - wsgiref == 0.1.2 12:23:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:32 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:32 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:23:32 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:23:32 INFO - Using env: {'DISPLAY': ':0', 12:23:32 INFO - 'HOME': '/home/cltbld', 12:23:32 INFO - 'LANG': 'en_US.UTF-8', 12:23:32 INFO - 'LANGUAGE': 'en_US:en', 12:23:32 INFO - 'LOGNAME': 'cltbld', 12:23:32 INFO - 'MAIL': '/var/mail/cltbld', 12:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:32 INFO - 'MOZ_NO_REMOTE': '1', 12:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:32 INFO - 'NO_EM_RESTART': '1', 12:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:32 INFO - 'PWD': '/builds/slave/test', 12:23:32 INFO - 'SHELL': '/bin/bash', 12:23:32 INFO - 'SHLVL': '1', 12:23:32 INFO - 'TERM': 'linux', 12:23:32 INFO - 'TMOUT': '86400', 12:23:32 INFO - 'USER': 'cltbld', 12:23:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:32 INFO - '_': '/tools/buildbot/bin/python'} 12:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 INFO - Cleaning up... 12:23:32 INFO - Return code: 0 12:23:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:32 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 0x7f03fd5c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2403ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:32 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:23:32 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:23:32 INFO - Using env: {'DISPLAY': ':0', 12:23:32 INFO - 'HOME': '/home/cltbld', 12:23:32 INFO - 'LANG': 'en_US.UTF-8', 12:23:32 INFO - 'LANGUAGE': 'en_US:en', 12:23:32 INFO - 'LOGNAME': 'cltbld', 12:23:32 INFO - 'MAIL': '/var/mail/cltbld', 12:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:32 INFO - 'MOZ_NO_REMOTE': '1', 12:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:32 INFO - 'NO_EM_RESTART': '1', 12:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:32 INFO - 'PWD': '/builds/slave/test', 12:23:32 INFO - 'SHELL': '/bin/bash', 12:23:32 INFO - 'SHLVL': '1', 12:23:32 INFO - 'TERM': 'linux', 12:23:32 INFO - 'TMOUT': '86400', 12:23:32 INFO - 'USER': 'cltbld', 12:23:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:32 INFO - '_': '/tools/buildbot/bin/python'} 12:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:23:32 INFO - Cleaning up... 12:23:32 INFO - Return code: 0 12:23:32 INFO - Running post-action listener: _resource_record_post_action 12:23:32 INFO - Running post-action listener: _start_resource_monitoring 12:23:32 INFO - Starting resource monitoring. 12:23:32 INFO - [mozharness: 2016-11-14 20:23:32.975167Z] Finished create-virtualenv step (success) 12:23:32 INFO - [mozharness: 2016-11-14 20:23:32.975754Z] Running install step. 12:23:32 INFO - Running pre-action listener: _resource_record_pre_action 12:23:32 INFO - Running main action method: install 12:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:33 INFO - Reading from file tmpfile_stdout 12:23:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:33 INFO - Reading from file tmpfile_stdout 12:23:33 INFO - Output received: 12:23:33 INFO - Usage: mozinstall [options] installer 12:23:33 INFO - Options: 12:23:33 INFO - -h, --help show this help message and exit 12:23:33 INFO - -d DEST, --destination=DEST 12:23:33 INFO - Directory to install application into. [default: 12:23:33 INFO - "/builds/slave/test"] 12:23:33 INFO - --app=APP Application being installed. [default: firefox] 12:23:33 INFO - mkdir: /builds/slave/test/build/application 12:23:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:23:46 INFO - Reading from file tmpfile_stdout 12:23:46 INFO - Output received: 12:23:46 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:46 INFO - Running post-action listener: _resource_record_post_action 12:23:46 INFO - [mozharness: 2016-11-14 20:23:46.791809Z] Finished install step (success) 12:23:46 INFO - [mozharness: 2016-11-14 20:23:46.792055Z] Running run-tests step. 12:23:46 INFO - Running pre-action listener: _resource_record_pre_action 12:23:46 INFO - Running main action method: run_tests 12:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:46 INFO - Python 2.7.3 12:23:46 INFO - Return code: 0 12:23:46 INFO - grabbing minidump binary from tooltool 12:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/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 0x2400f60>, '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 0x2401d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24021d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:23:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:23:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:23:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:46 INFO - Return code: 0 12:23:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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:23:46 INFO - Using env: {'DISPLAY': ':0', 12:23:46 INFO - 'HOME': '/home/cltbld', 12:23:46 INFO - 'LANG': 'en_US.UTF-8', 12:23:46 INFO - 'LANGUAGE': 'en_US:en', 12:23:46 INFO - 'LOGNAME': 'cltbld', 12:23:46 INFO - 'MAIL': '/var/mail/cltbld', 12:23:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:46 INFO - 'MOZ_NO_REMOTE': '1', 12:23:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:46 INFO - 'NO_EM_RESTART': '1', 12:23:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:46 INFO - 'PWD': '/builds/slave/test', 12:23:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:46 INFO - 'SHELL': '/bin/bash', 12:23:46 INFO - 'SHLVL': '1', 12:23:46 INFO - 'TERM': 'linux', 12:23:46 INFO - 'TMOUT': '86400', 12:23:46 INFO - 'USER': 'cltbld', 12:23:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451', 12:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:46 INFO - '_': '/tools/buildbot/bin/python'} 12:23:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:23:47 INFO - mozversion application_buildid: 20161114091743 12:23:47 INFO - mozversion application_changeset: fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 12:23:47 INFO - mozversion application_display_name: Firefox 12:23:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:47 INFO - mozversion application_name: Firefox 12:23:47 INFO - mozversion application_remotingname: firefox 12:23:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:47 INFO - mozversion application_vendor: Mozilla 12:23:47 INFO - mozversion application_version: 51.0 12:23:47 INFO - mozversion platform_buildid: 20161114091743 12:23:47 INFO - mozversion platform_changeset: fd3c6c15ec64591b145ab1b6dd71c5a2e7b8d9b1 12:23:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:47 INFO - mozversion platform_version: 51.0 12:23:47 INFO - using testdate: 1479155027 12:23:47 INFO - actual date: 1479155027 12:23:47 INFO - starting webserver on 'localhost:45364' 12:23:47 INFO - SUITE-START | Running 2 tests 12:23:47 INFO - TEST-START | dromaeo_css 12:23:47 INFO - operating with platform_type : linux_ 12:23:47 INFO - Initialising browser for dromaeo_css test... 12:23:47 INFO - TEST-INFO | started process 19666 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKD6ONC/profile http://localhost:45364/getInfo.html) 12:23:50 INFO - PROCESS | 19666 | __metrics Screen width/height:1600/1200 12:23:50 INFO - PROCESS | 19666 | colorDepth:24 12:23:50 INFO - PROCESS | 19666 | Browser inner width/height: 1024/697 12:23:50 INFO - PROCESS | 19666 | __metrics 12:23:51 INFO - PROCESS | 19666 | 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:23:51 INFO - PROCESS | 19666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:23:51 INFO - PROCESS | 19666 | console.error: PushService: 12:23:51 INFO - PROCESS | 19666 | stateChangeProcessEnqueue: Error transitioning state 12:23:51 INFO - PROCESS | 19666 | UnknownError 12:23:52 INFO - TEST-INFO | 19666: exit 0 12:23:52 INFO - Browser initialized. 12:23:52 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:52 INFO - TEST-INFO | started process 19758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKD6ONC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:04 INFO - PROCESS | 19758 | RSS: Main: 143134720 12:24:04 INFO - PROCESS | 19758 | 12:24:55 INFO - PROCESS | 19758 | 1479155095125 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:27:04 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45364/tests/dromaeo/cssquery-ext.html) 12:27:05 INFO - PROCESS | 19758 | RSS: Main: 255459328 12:27:05 INFO - PROCESS | 19758 | 12:30:07 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-ext.html (next: http://localhost:45364/tests/dromaeo/cssquery-jquery.html) 12:30:08 INFO - PROCESS | 19758 | RSS: Main: 228585472 12:30:08 INFO - PROCESS | 19758 | 12:33:10 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45364/tests/dromaeo/cssquery-mootools.html) 12:33:10 INFO - PROCESS | 19758 | RSS: Main: 175194112 12:33:10 INFO - PROCESS | 19758 | 12:36:12 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45364/tests/dromaeo/cssquery-prototype.html) 12:36:13 INFO - PROCESS | 19758 | RSS: Main: 173756416 12:36:13 INFO - PROCESS | 19758 | 12:39:15 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45364/tests/dromaeo/cssquery-yui.html) 12:39:16 INFO - PROCESS | 19758 | RSS: Main: 175910912 12:39:16 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | Cycle 1(1): loaded http://localhost:45364/tests/dromaeo/cssquery-yui.html (next: http://localhost:45364/tests/dromaeo/cssquery-dojo.html) 12:42:19 INFO - PROCESS | 19758 | RSS: Main: 260382720 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | __start_tp_report 12:42:19 INFO - PROCESS | 19758 | _x_x_mozilla_page_load 12:42:19 INFO - PROCESS | 19758 | _x_x_mozilla_page_load_details 12:42:19 INFO - PROCESS | 19758 | |i|pagename|runs| 12:42:19 INFO - PROCESS | 19758 | |0;dojo.html;3141.120486263846;3185;3199.056278397874;3207.3585282943386;3234.9838250808743;5602;5704.058830293002;5709;5734.569907256962;5747.511461525775;5591.944080559193;5634.239377684012;5657.830265092043;5662.122371032497;5672.517835983936;5070.74646267687;5074.2895994560695;5137.51193636604;5145.3311069560905;5152;4953.777080031389;5117.769700363493;5134.743262836843;5145.33110695611;5171.534561889429;4969.279454479099;5055.31753213317;5098.745062746848;5105.795768169269;5109.948900511003;5126.769295381717;5151.871203219912;5158.509941555546;5218.086834803932;5229.320188375525;5054.9241761373605;5124.359455068116;5177.275181474596;5193.065248255312;5223.843284701465;5408.810691625774;5445.863853403657;5537.0863807471715;5584.21820945068;5592.99326121298;6045.33501314855;6064.96967515164;6074.848128796772;6079.179310793031;6084.6045007074845;6036.5170786336985;6038.033914573691;6045.153678485016;6046.2744470663365;6053.545984051169;5441;5480.47935446128;5483.479069488392;5534.308211473566;5535.446455354432;6005.819825405245;6059.151718759377;6070.544709146831;6073.635581865102;6075.605085669417;5817.796377126865;5841.153032810304;5872.354041055481;5914.172015917775;5943.881122377615;6104.969475152596;6110.4500594945775;6112.541559383064;6114.193307625326;6116.327204007555;6055.636661800306;6065.54508411862;6066.696665166813;6067.969660151671;6069.969650151721;5705.115707065412;5724.112762521816;6003.189569408074;6065.3934606539;6093.786717464867;4653.255479123324;4705.411823522059;4709.740964246931;4727;4729.787159577827;4151.91696166075;4161;4161.04296011919;4170.895727606834;4186.28833098374;5580.1629755537;5623.493885550239;5873.618214816023;5874.060150375919;5885.087811389241;5903.852403689943;5922.111683247547;5957.582969192202;6056.273247210275;6093.573449858467;5733.88532229353;5734.455226753452;5735.139729040678;5736.196932429463;5748.568857335633;5106.387233532;5238.371395432572;5241.790328386822;5247.895042099137;5253.947460525423;6117.035835319757;6210.689465526705;6249.718762655601;6270.749170033238;6366.4588509977175;6126.326104128631;6161.599496032743;6166.81499555014;6167.259928808392;6171.444569988723;6077.27072751255;6301.338359472189;6360.872782544416;6379.904301435389;6384.361563843579;5815.621984570989;5907.763689452374;5920.79277225295;5956.791512297049;6058.848528786815;5986.730597123053;5995.1906492624685;6011.458968692838;6026.517878569792;6068.696565171812;5906.26171728534;5951.404859514014;5968.582199246185;5971.28344598651;5975.581709280309;5634.492895639412;5774.39368866263;5793.594448388572;5799.94200057994;5886.205362276038;5842.591018628656;5859.091840764689;5878.441548052928;5906.970465147647;5919.6744179070565;5892.263467066617;5896.115582662635;5899.056151015989;5902.026165682786;5904.202932604044;5530.557555395479;5579.581531385244;5615.943840561706;5624.212610234652;5658.03813351723 12:42:19 INFO - PROCESS | 19758 | |1;ext.html;576.8183022347963;616.2143267334149;640.9166808314919;656.5994743206641;661.3056290894559;46082.30876536851;46101.30848037282;46236.76881615587;46597;50926.23610645843;49130.75434622831;50018;50042.499575004236;50120.245791397305;50169.24746128811;8387;9157.313201509864;9330.253579713606;9345;9369.531523423835;8302.377321700866;10717.678469645924;10969.396683182422;11017.669469915916;11390.54437822486;11201.439928003609;11254.718632034183;11261.268017578832;11261.774764504706;11268.09855211581;10914.727131821688;10961.725956851064;10963;10997.065249453806;11003.394813285266;10894.727631809188;10947.56209751609;10947.726306842313;10961.12311015125;10976.341419514774;44863.775681121384;45006.549934500894;45083.77458112688;45094;45172;3770.3487647396214;3781.8487260509546;3937.7046721495717;3965.1078507335906;3990.4213888986224;34770.652293477;34856.65143348585;34902.65097349019;35253.47119793205;36804.631953680386;3928.7053470989504;3985.422113793513;4058.147788964321;4061.0253539150685;4131.566185550504;339545;341081;342142;342172;342284.288578558;39324.21351572953;40350.59649403527;40429.79785101055;61886.38113618896;61960.38039619546;41711.1657766843;41759;41801.58198417977;41827.7908610455;41833;17129.400470983455;17352.65294694124;17373.565660858327;17386.391476298268;17393.043382614076;12580.685482862818;12751.744965100646;14277.357518911675;14404;14523.564293071226;9427.670031548863;9472;9529.190018848476;9600.903990960001;9631.614735410505;3592.9101772455374;3634.491171236029;3669.669729724284;3705.3886108792285;3726.869559565402;1019.1490105761771;1084.219362059316;1089.6186334782922;1123.9325640461598;1137.272145826655;34705.30589388198;34923;34929.65070349264;34966.47550286748;35285.29429411398;1135.4663308245113;1136.8578927634046;1144.2619510415902;1201.5734414282979;1228.956986505468;18514.351997680016;18580.535486612942;18609.627807443776;18759.81240187608;18837.811621883604;547024;551492;555570;556471;556532;62911.685441572496;63089.36910630927;63401.68299158567;63558.36441635617;63563.04655430172;1260.6407173955379;1295.1452041652465;1323.7948118041718;1429.27821450167;1450.2676148544917;31456.842715786275;34444.1792469715;36226;36348;36569.817150914074;55263;56107;56129;56159.71920140373;56220;495518;500922;502955;503488;503638;40509.79745101256;41229.1754164921;46252.07495850132;47558.762206188745;48875.51124488709;52233.7388313056;52249.738751306;52317.7384113077;52494;52624.73687631537;37191.44212836864;37208.81395593005;37240;37418.43872341863;37520.62479375171;43851.34222986594;44047.77976110099;45252.32121518114;45498.09003819839;46316.536834631224;43824;45024.54975450203;45110.54889451195;45184.54815451804;45637.54362456333;37220.62779372172;39161.41257881191;39196.41205381865;39436.60563394444;40438.19123617452;29411.558826618046;30137.397252054398;30154.246143845703;30510.389792203587;30602.693973059984 12:42:19 INFO - PROCESS | 19758 | |2;jquery.html;424.3274410060055;440.61226121013505;442.18196336776964;450.55845271633797;451.7221908526257;18952.147153378097;19185.712214316798;20614.587708245846;20629.484262893435;20830.062647180876;19199.13603887825;19556.41330760076;19564.706529402036;19794.406167814952;19913.701294480557;5741.282339707526;6008.519318454513;6012.9398706013035;6015.548833837458;6018.458338749533;5741.224934633813;5882.647041177543;5938.317093534256;5940.316863560703;6026.8794624107495;5891.675957822318;5898.823035308927;5901.586888917777;5901.586888917777;5908.704564771766;5656.208130861662;5842.941570584304;5881.67650779207;5891.646501209942;5901.645901245917;5288.550473209782;5298.178782288752;5303.7082960437165;5312.73436328182;5314.282571852789;22858.54282914349;23046.423839403982;23465.882670586707;23545.646815297783;23761.405964850845;2440.755924407563;2862.41320529291;3177.602799650044;3213.3252017076443;3281.6554261802407;10324.948375258151;10651.84022239667;10677.71867375912;10742.462876856125;10794.676159715222;1968.2225520919158;2022.1203776357331;2047.3448496481133;2069.4929742213253;2081.7918208179212;85604;85793.14206857914;88436.11563884343;90288.54855725745;91297;11163.888361116286;11206.943965280121;11215.71960700989;11397.544098235978;11938.52245910154;16742.079185644907;16749.665006699797;16767.077810720282;16782;16794.58013549671;8629.180227878342;8769.912300877037;8824.117588241254;8884.84497015384;8961.59672814725;8470.745877623682;8531.488110713377;8615.569221538897;8639.481631102153;8861.778455538533;5079.085764562415;5093.337866077461;5098.770555325019;5103.413107492686;5136.126858434075;2058.9794102059086;2096.727425434684;2116.957660846805;2121;2157.579272041968;797.9880301795478;974;978.0806042320196;998.9250806189565;1138.8804175561613;17719.379821706178;18590;19212.615747685246;19270.90364548196;19272.80727192738;995.8655581496405;1025.2823023883311;1040.2718097331897;1059.184427990443;1115.358668765447;10553.894461055292;10937.398443085547;10942.562297508011;10979.890201098047;11020.944895275472;511683;514501;515554;517305;520144;97909;106135;106243;107942;110548.4472577632;1060.6658902445704;1111.666500049998;1137.533611219396;1180.8405865208258;1189.637160665988;17321.393751218646;17384.65230695404;17545.210465529337;17751.201195945956;18130.818691812914;41561.792191038854;41935.580644194386;41953.790231048646;42922.35616465693;43619.78190109029;56565.151522726366;57948;58536;59095.40904590899;59914.40085599088;8797.868031979653;9009.729708108767;9013.909860901307;9042.63829446841;9126.17864392208;10653.9467302663;12909.54816581415;13002.869971300166;13388.397522111723;13821;10807.891921081004;10827.891721082688;10953.233273670767;11070.114390848874;11640.126990475579;10753.408562529135;10823.837642435212;11261.887381126084;11306.717332066764;11594.71013224676;10973.561057557608;10975.945120274348;11034.724131896766;11162.665120046411;11408.315501069963;9120.179183873483;9185.311101667536;9205.125513076247;9424.858627120735;9474.66838660671;10294.485275736333;11320.716982075513;11370.94314528422;11481.885181148082;11754.823677644672 12:42:19 INFO - PROCESS | 19758 | |3;mootools.html;1499.7450433426316;1533.2103966457273;1722.4660355289861;1808.5749848785542;1853.5366158460386;8888.600012999414;9507.334486585942;9509.667161649335;9512.47681377524;9519.048095190477;8364.121767214447;8645.956770216157;9042.909570904289;9281.257499400048;9297.302702297338;2529.4435224250683;2539.174768200333;2552.3236342369287;2556.143691863228;2557.3350928758473;2437.914672986446;2543.694756629207;2566.358410397401;2572.4083460804027;2574.0476023871192;2522.798176145904;2532.4428625702317;2554.7062087859854;2556.8465892046443;2558.769710726034;2468.5680005999056;2478.50429914017;2488.4027833319965;2500.249925022504;2506.624006399055;2470.6788117544606;2473.777360037587;2477.1206221791185;2478.615814548748;2506.6490691303234;9535.856962145574;9552.90447095527;9602.039796020412;9605.37565058269;9620.470874101922;5582.91625625616;5685.943140568583;5744.22452968848;5749.166370876242;5761.452661997104;3351.279474912881;3453.326601312745;3521.6302288259876;3533.134382076382;3552.8401221945073;1160.7040204747734;1198.2930071258;1210.334316126136;1223.2232532341939;1225.81612758087;13327;13367.331633418387;13392.531261405902;13410.664733381645;13427.12723672958;4880.1215781159735;4937.5556199942175;5049;5198.064348417245;5228.2941802856885;11288;11305.71735706614;11332.830007549894;11344.602938897264;11352.035077018545;5838.182654428383;5842.474177324061;5842.59101862874;5852.736626851802;5856.033754430557;4661.976690116528;4765.142274390573;5287.524122828964;5334.1732031535175;5347.759350829222;4296.892577685583;4333.544977777351;4416.668749843775;4453.75504347264;4460.1971645104195;1314.8948084153244;1315.7039666075057;1348.6830594810212;1359.232033900843;1387.8959078069186;859.4499520307008;903.4218100415608;1022.3150489172273;1025.176093172456;1028.922830787694;6035.909461358083;6127.295361033538;6168.228971378578;6322.051692246107;6364.395382438661;516.1509317173229;516.4087120247295;520.786477544205;522.3914140026902;529.6742503360483;4338.197433474817;4439.4006809080365;4463.5313292103865;4465.12929978651;4528.320751887243;11624.709382265512;11962.043036557054;12065.215760975509;12082.57710980129;12097.092718046182;11142.721431964266;11654.125940554492;11667.76664466706;11953.760924781629;12005.039596832232;8822.206001459772;9001.909980900107;9012.233960113464;9040.231580315616;9045.638174472948;5956.463918247378;6147.815565533041;6400.679966001774;6519.445847103049;6658.900116498353;10553.997370249814;10920.399378034284;10921.672349829518;11076.11391088727;11085.44572771342;11063.834042489209;11159.944200278947;11263.380514071805;11273.492692829006;11284.605038823931;7527.360174385239;7736.8452630948195;7752.883706744291;7996.32031277348;8179.959100204461;8546.102659220693;8863.157999989991;9066.410683305829;9246.95376523113;9260.861087083564;7898.921010790048;8110.756677299691;8200.220979006985;8274.793130171553;8508.787280317794;8118.147594502729;8133.227343402367;8231.46495477792;8293.377996650395;8329.95835020821;7364;7481.177070522347;7507.962460187664;7590.430717696085;7639.427042971911;5219;5280.788768449219;5379.488948549882;5472.2065300531995;5542.140968149944;5522.171674248895;5569.164625306236;5622.550195984393;5673.574481913791;5738.971305143447 12:42:19 INFO - PROCESS | 19758 | |4;prototype.html;344.9896503104907;354.2737388353874;354.3126334910274;354.6843309454585;363.24626400219535;5581.832545023645;5597.804076857311;5598.13228949513;5646.520045796102;5658.377578466366;5422.779874528239;5443.591730620209;5529;5555.611107222496;5562.193481945115;3946.565877753459;4079.5925405735047;4128.690348223895;4271.914561708764;4353.738775673454;4313.870583882473;4328.372386004027;4330.285502892019;4338.349247612852;4350.173467041268;4340.218760623086;4340.479142502888;4353.738775673454;4354.847580334689;4368.322909948947;4095.488063992001;4308.633766129883;4325.307950727884;4357.694961352707;4364.585364390394;3784.791836448994;3798.6771124454453;3805.46723458716;3807.2385522895534;3843.7117216208894;5317.351620997503;5343.851072019495;5351.759170837322;5365.865853353658;5384.811531596414;4808.735519546423;4945.975270123627;4968.254761785725;4991.900161996739;5000.874978125539;5671.731307477001;5684.204211410406;5696.715164241813;5735.4551317624755;5747.482726554588;4187.099773548671;4191.455110835603;4197.727147735387;4200.991761977134;4208.452901122865;5819.533961328706;5960.791372301992;5968.612040217372;5974.492168165668;5995.250593675791;4277.379779931893;4281.293586558245;4283.314669652903;4283.4793648255045;4292.356146577976;4362.280223763107;4371.622938774276;4376.781160942004;4383.978080109643;4390.078083602415;4817.9036419271415;4964.230544265644;4968.900621987612;4975.387921341252;5000.249962505581;5714.200011998323;5716.371199168088;5727.570432217517;5761.761221337432;5814.534837213013;1477.7709455034487;1492.9552113436614;1496.102338596855;1502.4516051641071;1506.9623259418383;931.1340453378288;949.3291806420749;966.6520052780993;971.6162115964296;981.8723565936383;4878.82876130518;4906.607471402279;4923.655344125877;4951.232558953368;4989.750512474362;5070;5108.3103780989095;5122.462141475166;5132.099604300758;5142.640015198975;4618.515055919147;4864.902701946012;4921.803127874917;4935.555799977948;4937.728424936591;2743;2744.8764805583796;2760.986195069052;2762.7651649610007;2771.279467338466;5272.521914700084;5310.734463276821;5329.680219186861;5336.493033161843;5346.946530534723;5240.475952404805;5255.947440525622;5665.745041473144;6043.3050199227655;6083.8174854754425;5830.475257226785;6032.487238584682;6043.48630366415;6052.57631965767;6057.545684073712;5828.970855145697;5931.3178984417345;5961.582689211887;5986.910196347146;5991.940080599313;5653.604247702621;5885.705714714161;5901.7639294427745;5918.615290006136;5944.881102377842;6112.419320164578;6182.258129024366;6189.195404597373;6196.535259855619;6203.906941395973;4278.935815962701;4289.163613095417;4311.655067594522;4319.97840010798;4327.307630779061;5715.257016587817;5882.676452794966;5896.911546326723;5897.882042359214;5900.734466949083;5795.362510123968;5797.391273916178;5808.925348810315;5822.243108395882;5822.970885145547;5501.724913754377;5767.365589785037;5802.274715660543;5816.243888294494;5830.387809279964;5717.685527295872;5729.197912292366;5793.15999180125;5803.071508558611;5810.796622118206;5820.272465941757;5825.50483208932;5825.922204392201;5832.475077243069;5832.850260859089;5512;5654.15187721845;5667.773289068391;5679.176519404746;5692.146178073156 12:42:19 INFO - PROCESS | 19758 | |5;yui.html;813.154319507712;817.7791996161035;820.3642177312582;836.5900708652762;927.8422668146414;17764.200610972504;18367.816321836777;18474.630507389844;18516.166772495253;18548.72176917344;16877;17148.914255428735;18058.187381567826;18237.544061398472;18237.7264341035;740.5334639177311;846.7205822078699;865.7965378136133;867.0679020053436;868.622149365025;749.1310080306845;860.0797147052656;878.2403221213636;882.5102068352062;883.7127933421632;935.443411170356;937.2501998401286;938.4838338913604;938.7371535969905;941.5057095025098;929.419113054341;931.1479995803832;931.2084727981196;931.7344556801313;933.5705575435308;685.2428066986008;687.5290426058166;688.132952479874;688.6900894597401;689.152342618577;16564.746591892213;16597;16670.16649167537;16702.24839882196;16805.15974201285;15279.618009549738;16273.755893661604;16464.341426342933;16489.752653710202;16646;12388.81416778749;12974;13494.52769153075;13521.59435216945;13619.387127579184;4114.136031433402;4180.28935081037;4181.393697913785;4269.530351661315;4322.459692538433;80093.79859302116;80336.59831700863;80636;80675;80852.19147808505;11615;11851.111166662362;11919.344436055926;12481;12501.312427816558;22509.662355064687;22515.099396024303;22546.21088261903;22570.548589028127;22591.322260331886;8391.454555453876;8760.561971901507;8892.822143557092;9284.350095493252;9611;5127.154019586801;5340.759665815048;5426.104692725735;5442.700651464207;5568.192612071308;3853.344931361675;4025.4364388985564;4025.4565633639086;4025.8590949316635;4025.879223623296;656.9638669873108;665.1718610330073;676.6413800685546;679.6397909108181;686.4611280145062;656.1371796088129;664.5248647217263;677.295612562929;692.3180667042959;712.4727701500852;9887.555060022316;10237.157311683819;10742.892571074346;11198.982633231286;11224.270422422516;617.9907301390483;650.3334082565408;696.6760456387804;701.3897908819359;707.1090426063197;5021.422536408287;5073.34046573943;5117.641765076483;5135.87160320995;5231.006108839309;72129;106673;128791.35604321919;128865.35567322103;133040.33479832538;107185;107890.46054769676;109413.35879961807;109976.4014159153;116064;60968.695156524824;78648.21351786409;79902.6004869972;80062.19937800548;94570.52714736383;9244.075592440971;9454.14912657864;9699.369540979813;9937.65218217359;10220.421863371375;47764.7611761939;50915.2362714567;51574.74212629063;54330.18504722353;55276.40703354269;71484;74281.6285918567;76766.61616691881;78723.60638196772;79087.60456197683;13277.136986095873;13845.446182153004;13851.792223116863;14054.78917816254;14065.156090634595;13804.17174969505;14455.78316325277;15049.699006020037;16199.919000404923;17069.91465042667;14252.643683907985;14595.197264150574;15289.235538223267;15366.462173823862;15401.537953861402;14721.411143554566;14999.77500337474;15352.923235383752;16020.958637688978;16493;11549.769004620028;13943;14131;14207.786883196553;14297.35661895238;12074.335911524951;12689.555865544662;12701.8094728581;12895.742085158432;13128.146670466389;11931.164818462625;12322.32227227511;12487.812682809947;12678.556250531186;12983 12:42:19 INFO - PROCESS | 19758 | __end_tp_report 12:42:19 INFO - PROCESS | 19758 | __start_cc_report 12:42:19 INFO - PROCESS | 19758 | _x_x_mozilla_cycle_collect,931 12:42:19 INFO - PROCESS | 19758 | __end_cc_report 12:42:19 INFO - PROCESS | 19758 | __startTimestamp1479156139471__endTimestamp 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | ------- Summary: start ------- 12:42:19 INFO - PROCESS | 19758 | Number of tests: 6 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#0] dojo.html Cycles:165 Average:5595.63 Median:5799.94 stddev:639.03 (11.0%) stddev-sans-first:611.29 12:42:19 INFO - PROCESS | 19758 | Values: 3141.1 3185.0 3199.1 3207.4 3235.0 5602.0 5704.1 5709.0 5734.6 5747.5 5591.9 5634.2 5657.8 5662.1 5672.5 5070.7 5074.3 5137.5 5145.3 5152.0 4953.8 5117.8 5134.7 5145.3 5171.5 4969.3 5055.3 5098.7 5105.8 5109.9 5126.8 5151.9 5158.5 5218.1 5229.3 5054.9 5124.4 5177.3 5193.1 5223.8 5408.8 5445.9 5537.1 5584.2 5593.0 6045.3 6065.0 6074.8 6079.2 6084.6 6036.5 6038.0 6045.2 6046.3 6053.5 5441.0 5480.5 5483.5 5534.3 5535.4 6005.8 6059.2 6070.5 6073.6 6075.6 5817.8 5841.2 5872.4 5914.2 5943.9 6105.0 6110.5 6112.5 6114.2 6116.3 6055.6 6065.5 6066.7 6068.0 6070.0 5705.1 5724.1 6003.2 6065.4 6093.8 4653.3 4705.4 4709.7 4727.0 4729.8 4151.9 4161.0 4161.0 4170.9 4186.3 5580.2 5623.5 5873.6 5874.1 5885.1 5903.9 5922.1 5957.6 6056.3 6093.6 5733.9 5734.5 5735.1 5736.2 5748.6 5106.4 5238.4 5241.8 5247.9 5253.9 6117.0 6210.7 6249.7 6270.7 6366.5 6126.3 6161.6 6166.8 6167.3 6171.4 6077.3 6301.3 6360.9 6379.9 6384.4 5815.6 5907.8 5920.8 5956.8 6058.8 5986.7 5995.2 6011.5 6026.5 6068.7 5906.3 5951.4 5968.6 5971.3 5975.6 5634.5 5774.4 5793.6 5799.9 5886.2 5842.6 5859.1 5878.4 5907.0 5919.7 5892.3 5896.1 5899.1 5902.0 5904.2 5530.6 5579.6 5615.9 5624.2 5658.0 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#1] ext.html Cycles:180 Average:63195.77 Median:21630.96 stddev:125785.91 (581.5%) stddev-sans-first:126050.91 12:42:19 INFO - PROCESS | 19758 | Values: 576.8 616.2 640.9 656.6 661.3 46082.3 46101.3 46236.8 46597.0 50926.2 49130.8 50018.0 50042.5 50120.2 50169.2 8387.0 9157.3 9330.3 9345.0 9369.5 8302.4 10717.7 10969.4 11017.7 11390.5 11201.4 11254.7 11261.3 11261.8 11268.1 10914.7 10961.7 10963.0 10997.1 11003.4 10894.7 10947.6 10947.7 10961.1 10976.3 44863.8 45006.5 45083.8 45094.0 45172.0 3770.3 3781.8 3937.7 3965.1 3990.4 34770.7 34856.7 34902.7 35253.5 36804.6 3928.7 3985.4 4058.1 4061.0 4131.6 339545.0 341081.0 342142.0 342172.0 342284.3 39324.2 40350.6 40429.8 61886.4 61960.4 41711.2 41759.0 41801.6 41827.8 41833.0 17129.4 17352.7 17373.6 17386.4 17393.0 12580.7 12751.7 14277.4 14404.0 14523.6 9427.7 9472.0 9529.2 9600.9 9631.6 3592.9 3634.5 3669.7 3705.4 3726.9 1019.1 1084.2 1089.6 1123.9 1137.3 34705.3 34923.0 34929.7 34966.5 35285.3 1135.5 1136.9 1144.3 1201.6 1229.0 18514.4 18580.5 18609.6 18759.8 18837.8 547024.0 551492.0 555570.0 556471.0 556532.0 62911.7 63089.4 63401.7 63558.4 63563.0 1260.6 1295.1 1323.8 1429.3 1450.3 31456.8 34444.2 36226.0 36348.0 36569.8 55263.0 56107.0 56129.0 56159.7 56220.0 495518.0 500922.0 502955.0 503488.0 503638.0 40509.8 41229.2 46252.1 47558.8 48875.5 52233.7 52249.7 52317.7 52494.0 52624.7 37191.4 37208.8 37240.0 37418.4 37520.6 43851.3 44047.8 45252.3 45498.1 46316.5 43824.0 45024.5 45110.5 45184.5 45637.5 37220.6 39161.4 39196.4 39436.6 40438.2 29411.6 30137.4 30154.2 30510.4 30602.7 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#2] jquery.html Cycles:180 Average:30488.22 Median:23256.15 stddev:85332.06 (366.9%) stddev-sans-first:85541.58 12:42:19 INFO - PROCESS | 19758 | Values: 424.3 440.6 442.2 450.6 451.7 18952.1 19185.7 20614.6 20629.5 20830.1 19199.1 19556.4 19564.7 19794.4 19913.7 5741.3 6008.5 6012.9 6015.5 6018.5 5741.2 5882.6 5938.3 5940.3 6026.9 5891.7 5898.8 5901.6 5901.6 5908.7 5656.2 5842.9 5881.7 5891.6 5901.6 5288.6 5298.2 5303.7 5312.7 5314.3 22858.5 23046.4 23465.9 23545.6 23761.4 2440.8 2862.4 3177.6 3213.3 3281.7 10324.9 10651.8 10677.7 10742.5 10794.7 1968.2 2022.1 2047.3 2069.5 2081.8 85604.0 85793.1 88436.1 90288.5 91297.0 11163.9 11206.9 11215.7 11397.5 11938.5 16742.1 16749.7 16767.1 16782.0 16794.6 8629.2 8769.9 8824.1 8884.8 8961.6 8470.7 8531.5 8615.6 8639.5 8861.8 5079.1 5093.3 5098.8 5103.4 5136.1 2059.0 2096.7 2117.0 2121.0 2157.6 798.0 974.0 978.1 998.9 1138.9 17719.4 18590.0 19212.6 19270.9 19272.8 995.9 1025.3 1040.3 1059.2 1115.4 10553.9 10937.4 10942.6 10979.9 11020.9 511683.0 514501.0 515554.0 517305.0 520144.0 97909.0 106135.0 106243.0 107942.0 110548.4 1060.7 1111.7 1137.5 1180.8 1189.6 17321.4 17384.7 17545.2 17751.2 18130.8 41561.8 41935.6 41953.8 42922.4 43619.8 56565.2 57948.0 58536.0 59095.4 59914.4 8797.9 9009.7 9013.9 9042.6 9126.2 10653.9 12909.5 13002.9 13388.4 13821.0 10807.9 10827.9 10953.2 11070.1 11640.1 10753.4 10823.8 11261.9 11306.7 11594.7 10973.6 10975.9 11034.7 11162.7 11408.3 9120.2 9185.3 9205.1 9424.9 9474.7 10294.5 11320.7 11370.9 11481.9 11754.8 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#3] mootools.html Cycles:180 Average:6222.54 Median:4908.84 stddev:3585.97 (73.1%) stddev-sans-first:3578.47 12:42:19 INFO - PROCESS | 19758 | Values: 1499.7 1533.2 1722.5 1808.6 1853.5 8888.6 9507.3 9509.7 9512.5 9519.0 8364.1 8646.0 9042.9 9281.3 9297.3 2529.4 2539.2 2552.3 2556.1 2557.3 2437.9 2543.7 2566.4 2572.4 2574.0 2522.8 2532.4 2554.7 2556.8 2558.8 2468.6 2478.5 2488.4 2500.2 2506.6 2470.7 2473.8 2477.1 2478.6 2506.6 9535.9 9552.9 9602.0 9605.4 9620.5 5582.9 5685.9 5744.2 5749.2 5761.5 3351.3 3453.3 3521.6 3533.1 3552.8 1160.7 1198.3 1210.3 1223.2 1225.8 13327.0 13367.3 13392.5 13410.7 13427.1 4880.1 4937.6 5049.0 5198.1 5228.3 11288.0 11305.7 11332.8 11344.6 11352.0 5838.2 5842.5 5842.6 5852.7 5856.0 4662.0 4765.1 5287.5 5334.2 5347.8 4296.9 4333.5 4416.7 4453.8 4460.2 1314.9 1315.7 1348.7 1359.2 1387.9 859.4 903.4 1022.3 1025.2 1028.9 6035.9 6127.3 6168.2 6322.1 6364.4 516.2 516.4 520.8 522.4 529.7 4338.2 4439.4 4463.5 4465.1 4528.3 11624.7 11962.0 12065.2 12082.6 12097.1 11142.7 11654.1 11667.8 11953.8 12005.0 8822.2 9001.9 9012.2 9040.2 9045.6 5956.5 6147.8 6400.7 6519.4 6658.9 10554.0 10920.4 10921.7 11076.1 11085.4 11063.8 11159.9 11263.4 11273.5 11284.6 7527.4 7736.8 7752.9 7996.3 8180.0 8546.1 8863.2 9066.4 9247.0 9260.9 7898.9 8110.8 8200.2 8274.8 8508.8 8118.1 8133.2 8231.5 8293.4 8330.0 7364.0 7481.2 7508.0 7590.4 7639.4 5219.0 5280.8 5379.5 5472.2 5542.1 5522.2 5569.2 5622.6 5673.6 5739.0 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#4] prototype.html Cycles:180 Average:4775.99 Median:5333.09 stddev:1399.67 (26.2%) stddev-sans-first:1363.51 12:42:19 INFO - PROCESS | 19758 | Values: 345.0 354.3 354.3 354.7 363.2 5581.8 5597.8 5598.1 5646.5 5658.4 5422.8 5443.6 5529.0 5555.6 5562.2 3946.6 4079.6 4128.7 4271.9 4353.7 4313.9 4328.4 4330.3 4338.3 4350.2 4340.2 4340.5 4353.7 4354.8 4368.3 4095.5 4308.6 4325.3 4357.7 4364.6 3784.8 3798.7 3805.5 3807.2 3843.7 5317.4 5343.9 5351.8 5365.9 5384.8 4808.7 4946.0 4968.3 4991.9 5000.9 5671.7 5684.2 5696.7 5735.5 5747.5 4187.1 4191.5 4197.7 4201.0 4208.5 5819.5 5960.8 5968.6 5974.5 5995.3 4277.4 4281.3 4283.3 4283.5 4292.4 4362.3 4371.6 4376.8 4384.0 4390.1 4817.9 4964.2 4968.9 4975.4 5000.2 5714.2 5716.4 5727.6 5761.8 5814.5 1477.8 1493.0 1496.1 1502.5 1507.0 931.1 949.3 966.7 971.6 981.9 4878.8 4906.6 4923.7 4951.2 4989.8 5070.0 5108.3 5122.5 5132.1 5142.6 4618.5 4864.9 4921.8 4935.6 4937.7 2743.0 2744.9 2761.0 2762.8 2771.3 5272.5 5310.7 5329.7 5336.5 5346.9 5240.5 5255.9 5665.7 6043.3 6083.8 5830.5 6032.5 6043.5 6052.6 6057.5 5829.0 5931.3 5961.6 5986.9 5991.9 5653.6 5885.7 5901.8 5918.6 5944.9 6112.4 6182.3 6189.2 6196.5 6203.9 4278.9 4289.2 4311.7 4320.0 4327.3 5715.3 5882.7 5896.9 5897.9 5900.7 5795.4 5797.4 5808.9 5822.2 5823.0 5501.7 5767.4 5802.3 5816.2 5830.4 5717.7 5729.2 5793.2 5803.1 5810.8 5820.3 5825.5 5825.9 5832.5 5832.9 5512.0 5654.2 5667.8 5679.2 5692.1 12:42:19 INFO - PROCESS | 19758 | 12:42:19 INFO - PROCESS | 19758 | [#5] yui.html Cycles:180 Average:21715.95 Median:4180.84 stddev:30711.63 (734.6%) stddev-sans-first:30757.68 12:42:19 INFO - PROCESS | 19758 | Values: 813.2 817.8 820.4 836.6 927.8 17764.2 18367.8 18474.6 18516.2 18548.7 16877.0 17148.9 18058.2 18237.5 18237.7 740.5 846.7 865.8 867.1 868.6 749.1 860.1 878.2 882.5 883.7 935.4 937.3 938.5 938.7 941.5 929.4 931.1 931.2 931.7 933.6 685.2 687.5 688.1 688.7 689.2 16564.7 16597.0 16670.2 16702.2 16805.2 15279.6 16273.8 16464.3 16489.8 16646.0 12388.8 12974.0 13494.5 13521.6 13619.4 4114.1 4180.3 4181.4 4269.5 4322.5 80093.8 80336.6 80636.0 80675.0 80852.2 11615.0 11851.1 11919.3 12481.0 12501.3 22509.7 22515.1 22546.2 22570.5 22591.3 8391.5 8760.6 8892.8 9284.4 9611.0 5127.2 5340.8 5426.1 5442.7 5568.2 3853.3 4025.4 4025.5 4025.9 4025.9 657.0 665.2 676.6 679.6 686.5 656.1 664.5 677.3 692.3 712.5 9887.6 10237.2 10742.9 11199.0 11224.3 618.0 650.3 696.7 701.4 707.1 5021.4 5073.3 5117.6 5135.9 5231.0 72129.0 106673.0 128791.4 128865.4 133040.3 107185.0 107890.5 109413.4 109976.4 116064.0 60968.7 78648.2 79902.6 80062.2 94570.5 9244.1 9454.1 9699.4 9937.7 10220.4 47764.8 50915.2 51574.7 54330.2 55276.4 71484.0 74281.6 76766.6 78723.6 79087.6 13277.1 13845.4 13851.8 14054.8 14065.2 13804.2 14455.8 15049.7 16199.9 17069.9 14252.6 14595.2 15289.2 15366.5 15401.5 14721.4 14999.8 15352.9 16021.0 16493.0 11549.8 13943.0 14131.0 14207.8 14297.4 12074.3 12689.6 12701.8 12895.7 13128.1 11931.2 12322.3 12487.8 12678.6 12983.0 12:42:19 INFO - PROCESS | 19758 | -------- Summary: end -------- 12:42:19 INFO - PROCESS | 19758 | 12:42:20 INFO - TEST-INFO | 19758: exit 0 12:42:20 INFO - TEST-OK | dromaeo_css | took 1113163ms 12:42:20 INFO - TEST-START | kraken 12:42:20 INFO - operating with platform_type : linux_ 12:42:20 INFO - Initialising browser for kraken test... 12:42:20 INFO - TEST-INFO | started process 20065 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_ewPIu/profile http://localhost:45364/getInfo.html) 12:42:23 INFO - PROCESS | 20065 | __metrics Screen width/height:1600/1200 12:42:23 INFO - PROCESS | 20065 | colorDepth:24 12:42:23 INFO - PROCESS | 20065 | Browser inner width/height: 1024/697 12:42:23 INFO - PROCESS | 20065 | __metrics 12:42:24 INFO - PROCESS | 20065 | 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:42:24 INFO - PROCESS | 20065 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:42:24 INFO - PROCESS | 20065 | console.error: PushService: 12:42:24 INFO - PROCESS | 20065 | stateChangeProcessEnqueue: Error transitioning state 12:42:24 INFO - PROCESS | 20065 | UnknownError 12:42:25 INFO - TEST-INFO | 20065: exit 0 12:42:25 INFO - Browser initialized. 12:42:25 INFO - Running cycle 1/1 for kraken test... 12:42:25 INFO - TEST-INFO | started process 20157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_ewPIu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:36 INFO - PROCESS | 20157 | RSS: Main: 142471168 12:42:36 INFO - PROCESS | 20157 | 12:42:39 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45364/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:40 INFO - PROCESS | 20157 | RSS: Main: 186707968 12:42:40 INFO - PROCESS | 20157 | 12:42:42 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45364/tests/kraken/driver.html?testName=audio-dft) 12:42:43 INFO - PROCESS | 20157 | RSS: Main: 174415872 12:42:43 INFO - PROCESS | 20157 | 12:42:46 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45364/tests/kraken/driver.html?testName=audio-fft) 12:42:46 INFO - PROCESS | 20157 | RSS: Main: 265244672 12:42:46 INFO - PROCESS | 20157 | 12:42:48 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45364/tests/kraken/driver.html?testName=audio-oscillator) 12:42:49 INFO - PROCESS | 20157 | RSS: Main: 171028480 12:42:49 INFO - PROCESS | 20157 | 12:42:51 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45364/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:52 INFO - PROCESS | 20157 | RSS: Main: 173457408 12:42:52 INFO - PROCESS | 20157 | 12:42:55 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45364/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:55 INFO - PROCESS | 20157 | RSS: Main: 209063936 12:42:55 INFO - PROCESS | 20157 | 12:42:59 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45364/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:59 INFO - PROCESS | 20157 | RSS: Main: 208363520 12:42:59 INFO - PROCESS | 20157 | 12:43:02 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45364/tests/kraken/driver.html?testName=json-parse-financial) 12:43:03 INFO - PROCESS | 20157 | RSS: Main: 207904768 12:43:03 INFO - PROCESS | 20157 | 12:43:04 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45364/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:05 INFO - PROCESS | 20157 | RSS: Main: 170082304 12:43:05 INFO - PROCESS | 20157 | 12:43:06 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:07 INFO - PROCESS | 20157 | RSS: Main: 178126848 12:43:07 INFO - PROCESS | 20157 | 12:43:09 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:10 INFO - PROCESS | 20157 | RSS: Main: 187486208 12:43:10 INFO - PROCESS | 20157 | 12:43:12 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:13 INFO - PROCESS | 20157 | RSS: Main: 195883008 12:43:13 INFO - PROCESS | 20157 | 12:43:16 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:16 INFO - PROCESS | 20157 | RSS: Main: 192458752 12:43:16 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | Cycle 1(1): loaded http://localhost:45364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45364/tests/kraken/driver.html?testName=ai-astar) 12:43:18 INFO - PROCESS | 20157 | RSS: Main: 244158464 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | __start_tp_report 12:43:18 INFO - PROCESS | 20157 | _x_x_mozilla_page_load 12:43:18 INFO - PROCESS | 20157 | _x_x_mozilla_page_load_details 12:43:18 INFO - PROCESS | 20157 | |i|pagename|runs| 12:43:18 INFO - PROCESS | 20157 | |0;ai-astar;113;113;113;115;115;112;112;112;112;112 12:43:18 INFO - PROCESS | 20157 | |1;audio-beat-detection;148;117;120;153;123;118;119;119;148;123 12:43:18 INFO - PROCESS | 20157 | |2;audio-dft;162;161;162;168;160;162;162;161;160;160 12:43:18 INFO - PROCESS | 20157 | |3;audio-fft;74;76;76;108;76;76;76;76;100;77 12:43:18 INFO - PROCESS | 20157 | |4;audio-oscillator;83;82;122;84;84;82;99;82;82;118 12:43:18 INFO - PROCESS | 20157 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 12:43:18 INFO - PROCESS | 20157 | |6;imaging-darkroom;146;146;146;146;147;147;146;147;146;146 12:43:18 INFO - PROCESS | 20157 | |7;imaging-desaturate;95;95;96;95;97;95;95;95;95;97 12:43:18 INFO - PROCESS | 20157 | |8;json-parse-financial;50;50;50;49;51;50;50;49;49;49 12:43:18 INFO - PROCESS | 20157 | |9;json-stringify-tinderbox;60;60;59;59;61;59;59;61;61;59 12:43:18 INFO - PROCESS | 20157 | |10;stanford-crypto-aes;74;82;73;74;75;73;86;74;80;73 12:43:18 INFO - PROCESS | 20157 | |11;stanford-crypto-ccm;125;136;124;129;122;130;120;121;163;138 12:43:18 INFO - PROCESS | 20157 | |12;stanford-crypto-pbkdf2;157;160;155;153;157;155;154;152;152;155 12:43:18 INFO - PROCESS | 20157 | |13;stanford-crypto-sha256-iterative;65;64;65;62;63;63;61;62;62;60 12:43:18 INFO - PROCESS | 20157 | __end_tp_report 12:43:18 INFO - PROCESS | 20157 | __start_cc_report 12:43:18 INFO - PROCESS | 20157 | _x_x_mozilla_cycle_collect,1595 12:43:18 INFO - PROCESS | 20157 | __end_cc_report 12:43:18 INFO - PROCESS | 20157 | __startTimestamp1479156198482__endTimestamp 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | ------- Summary: start ------- 12:43:18 INFO - PROCESS | 20157 | Number of tests: 14 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:1.20 (1.1%) stddev-sans-first:1.27 12:43:18 INFO - PROCESS | 20157 | Values: 113.0 113.0 113.0 115.0 115.0 112.0 112.0 112.0 112.0 112.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:123.00 stddev:14.59 (11.9%) stddev-sans-first:13.72 12:43:18 INFO - PROCESS | 20157 | Values: 148.0 117.0 120.0 153.0 123.0 118.0 119.0 119.0 148.0 123.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:2.35 (1.4%) stddev-sans-first:2.49 12:43:18 INFO - PROCESS | 20157 | Values: 162.0 161.0 162.0 168.0 160.0 162.0 162.0 161.0 160.0 160.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:12.03 (15.8%) stddev-sans-first:12.45 12:43:18 INFO - PROCESS | 20157 | Values: 74.0 76.0 76.0 108.0 76.0 76.0 76.0 76.0 100.0 77.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:82.50 stddev:15.75 (19.1%) stddev-sans-first:16.38 12:43:18 INFO - PROCESS | 20157 | Values: 83.0 82.0 122.0 84.0 84.0 82.0 99.0 82.0 82.0 118.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:43:18 INFO - PROCESS | 20157 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:43:18 INFO - PROCESS | 20157 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 12:43:18 INFO - PROCESS | 20157 | Values: 95.0 95.0 96.0 95.0 97.0 95.0 95.0 95.0 95.0 97.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 12:43:18 INFO - PROCESS | 20157 | Values: 50.0 50.0 50.0 49.0 51.0 50.0 50.0 49.0 49.0 49.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 12:43:18 INFO - PROCESS | 20157 | Values: 60.0 60.0 59.0 59.0 61.0 59.0 59.0 61.0 61.0 59.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:74.50 stddev:4.60 (6.2%) stddev-sans-first:4.80 12:43:18 INFO - PROCESS | 20157 | Values: 74.0 82.0 73.0 74.0 75.0 73.0 86.0 74.0 80.0 73.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:129.50 stddev:12.87 (9.9%) stddev-sans-first:13.47 12:43:18 INFO - PROCESS | 20157 | Values: 125.0 136.0 124.0 129.0 122.0 130.0 120.0 121.0 163.0 138.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:155.00 stddev:2.49 (1.6%) stddev-sans-first:2.54 12:43:18 INFO - PROCESS | 20157 | Values: 157.0 160.0 155.0 153.0 157.0 155.0 154.0 152.0 152.0 155.0 12:43:18 INFO - PROCESS | 20157 | 12:43:18 INFO - PROCESS | 20157 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.64 (2.6%) stddev-sans-first:1.51 12:43:18 INFO - PROCESS | 20157 | Values: 65.0 64.0 65.0 62.0 63.0 63.0 61.0 62.0 62.0 60.0 12:43:18 INFO - PROCESS | 20157 | -------- Summary: end -------- 12:43:18 INFO - PROCESS | 20157 | 12:43:19 INFO - TEST-INFO | 20157: exit 0 12:43:19 INFO - TEST-OK | kraken | took 59256ms 12:43:19 INFO - SUITE-END | took 1172s 12:43:19 INFO - Completed test suite (00:19:32) 12:43:19 INFO - javascript benchmark 12:43:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.120486263846, 3185.0, 3199.056278397874, 3207.3585282943386, 3234.9838250808743, 5602.0, 5704.058830293002, 5709.0, 5734.569907256962, 5747.511461525775, 5591.944080559193, 5634.239377684012, 5657.830265092043, 5662.122371032497, 5672.517835983936, 5070.74646267687, 5074.2895994560695, 5137.51193636604, 5145.3311069560905, 5152.0, 4953.777080031389, 5117.769700363493, 5134.743262836843, 5145.33110695611, 5171.534561889429, 4969.279454479099, 5055.31753213317, 5098.745062746848, 5105.795768169269, 5109.948900511003, 5126.769295381717, 5151.871203219912, 5158.509941555546, 5218.086834803932, 5229.320188375525, 5054.9241761373605, 5124.359455068116, 5177.275181474596, 5193.065248255312, 5223.843284701465, 5408.810691625774, 5445.863853403657, 5537.0863807471715, 5584.21820945068, 5592.99326121298, 6045.33501314855, 6064.96967515164, 6074.848128796772, 6079.179310793031, 6084.6045007074845, 6036.5170786336985, 6038.033914573691, 6045.153678485016, 6046.2744470663365, 6053.545984051169, 5441.0, 5480.47935446128, 5483.479069488392, 5534.308211473566, 5535.446455354432, 6005.819825405245, 6059.151718759377, 6070.544709146831, 6073.635581865102, 6075.605085669417, 5817.796377126865, 5841.153032810304, 5872.354041055481, 5914.172015917775, 5943.881122377615, 6104.969475152596, 6110.4500594945775, 6112.541559383064, 6114.193307625326, 6116.327204007555, 6055.636661800306, 6065.54508411862, 6066.696665166813, 6067.969660151671, 6069.969650151721, 5705.115707065412, 5724.112762521816, 6003.189569408074, 6065.3934606539, 6093.786717464867, 4653.255479123324, 4705.411823522059, 4709.740964246931, 4727.0, 4729.787159577827, 4151.91696166075, 4161.0, 4161.04296011919, 4170.895727606834, 4186.28833098374, 5580.1629755537, 5623.493885550239, 5873.618214816023, 5874.060150375919, 5885.087811389241, 5903.852403689943, 5922.111683247547, 5957.582969192202, 6056.273247210275, 6093.573449858467, 5733.88532229353, 5734.455226753452, 5735.139729040678, 5736.196932429463, 5748.568857335633, 5106.387233532, 5238.371395432572, 5241.790328386822, 5247.895042099137, 5253.947460525423, 6117.035835319757, 6210.689465526705, 6249.718762655601, 6270.749170033238, 6366.4588509977175, 6126.326104128631, 6161.599496032743, 6166.81499555014, 6167.259928808392, 6171.444569988723, 6077.27072751255, 6301.338359472189, 6360.872782544416, 6379.904301435389, 6384.361563843579, 5815.621984570989, 5907.763689452374, 5920.79277225295, 5956.791512297049, 6058.848528786815, 5986.730597123053, 5995.1906492624685, 6011.458968692838, 6026.517878569792, 6068.696565171812, 5906.26171728534, 5951.404859514014, 5968.582199246185, 5971.28344598651, 5975.581709280309, 5634.492895639412, 5774.39368866263, 5793.594448388572, 5799.94200057994, 5886.205362276038, 5842.591018628656, 5859.091840764689, 5878.441548052928, 5906.970465147647, 5919.6744179070565, 5892.263467066617, 5896.115582662635, 5899.056151015989, 5902.026165682786, 5904.202932604044, 5530.557555395479, 5579.581531385244, 5615.943840561706, 5624.212610234652, 5658.03813351723], "value": 5552.06434251789, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.8183022347963, 616.2143267334149, 640.9166808314919, 656.5994743206641, 661.3056290894559, 46082.30876536851, 46101.30848037282, 46236.76881615587, 46597.0, 50926.23610645843, 49130.75434622831, 50018.0, 50042.499575004236, 50120.245791397305, 50169.24746128811, 8387.0, 9157.313201509864, 9330.253579713606, 9345.0, 9369.531523423835, 8302.377321700866, 10717.678469645924, 10969.396683182422, 11017.669469915916, 11390.54437822486, 11201.439928003609, 11254.718632034183, 11261.268017578832, 11261.774764504706, 11268.09855211581, 10914.727131821688, 10961.725956851064, 10963.0, 10997.065249453806, 11003.394813285266, 10894.727631809188, 10947.56209751609, 10947.726306842313, 10961.12311015125, 10976.341419514774, 44863.775681121384, 45006.549934500894, 45083.77458112688, 45094.0, 45172.0, 3770.3487647396214, 3781.8487260509546, 3937.7046721495717, 3965.1078507335906, 3990.4213888986224, 34770.652293477, 34856.65143348585, 34902.65097349019, 35253.47119793205, 36804.631953680386, 3928.7053470989504, 3985.422113793513, 4058.147788964321, 4061.0253539150685, 4131.566185550504, 339545.0, 341081.0, 342142.0, 342172.0, 342284.288578558, 39324.21351572953, 40350.59649403527, 40429.79785101055, 61886.38113618896, 61960.38039619546, 41711.1657766843, 41759.0, 41801.58198417977, 41827.7908610455, 41833.0, 17129.400470983455, 17352.65294694124, 17373.565660858327, 17386.391476298268, 17393.043382614076, 12580.685482862818, 12751.744965100646, 14277.357518911675, 14404.0, 14523.564293071226, 9427.670031548863, 9472.0, 9529.190018848476, 9600.903990960001, 9631.614735410505, 3592.9101772455374, 3634.491171236029, 3669.669729724284, 3705.3886108792285, 3726.869559565402, 1019.1490105761771, 1084.219362059316, 1089.6186334782922, 1123.9325640461598, 1137.272145826655, 34705.30589388198, 34923.0, 34929.65070349264, 34966.47550286748, 35285.29429411398, 1135.4663308245113, 1136.8578927634046, 1144.2619510415902, 1201.5734414282979, 1228.956986505468, 18514.351997680016, 18580.535486612942, 18609.627807443776, 18759.81240187608, 18837.811621883604, 547024.0, 551492.0, 555570.0, 556471.0, 556532.0, 62911.685441572496, 63089.36910630927, 63401.68299158567, 63558.36441635617, 63563.04655430172, 1260.6407173955379, 1295.1452041652465, 1323.7948118041718, 1429.27821450167, 1450.2676148544917, 31456.842715786275, 34444.1792469715, 36226.0, 36348.0, 36569.817150914074, 55263.0, 56107.0, 56129.0, 56159.71920140373, 56220.0, 495518.0, 500922.0, 502955.0, 503488.0, 503638.0, 40509.79745101256, 41229.1754164921, 46252.07495850132, 47558.762206188745, 48875.51124488709, 52233.7388313056, 52249.738751306, 52317.7384113077, 52494.0, 52624.73687631537, 37191.44212836864, 37208.81395593005, 37240.0, 37418.43872341863, 37520.62479375171, 43851.34222986594, 44047.77976110099, 45252.32121518114, 45498.09003819839, 46316.536834631224, 43824.0, 45024.54975450203, 45110.54889451195, 45184.54815451804, 45637.54362456333, 37220.62779372172, 39161.41257881191, 39196.41205381865, 39436.60563394444, 40438.19123617452, 29411.558826618046, 30137.397252054398, 30154.246143845703, 30510.389792203587, 30602.693973059984], "value": 20669.084479566973, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.3274410060055, 440.61226121013505, 442.18196336776964, 450.55845271633797, 451.7221908526257, 18952.147153378097, 19185.712214316798, 20614.587708245846, 20629.484262893435, 20830.062647180876, 19199.13603887825, 19556.41330760076, 19564.706529402036, 19794.406167814952, 19913.701294480557, 5741.282339707526, 6008.519318454513, 6012.9398706013035, 6015.548833837458, 6018.458338749533, 5741.224934633813, 5882.647041177543, 5938.317093534256, 5940.316863560703, 6026.8794624107495, 5891.675957822318, 5898.823035308927, 5901.586888917777, 5901.586888917777, 5908.704564771766, 5656.208130861662, 5842.941570584304, 5881.67650779207, 5891.646501209942, 5901.645901245917, 5288.550473209782, 5298.178782288752, 5303.7082960437165, 5312.73436328182, 5314.282571852789, 22858.54282914349, 23046.423839403982, 23465.882670586707, 23545.646815297783, 23761.405964850845, 2440.755924407563, 2862.41320529291, 3177.602799650044, 3213.3252017076443, 3281.6554261802407, 10324.948375258151, 10651.84022239667, 10677.71867375912, 10742.462876856125, 10794.676159715222, 1968.2225520919158, 2022.1203776357331, 2047.3448496481133, 2069.4929742213253, 2081.7918208179212, 85604.0, 85793.14206857914, 88436.11563884343, 90288.54855725745, 91297.0, 11163.888361116286, 11206.943965280121, 11215.71960700989, 11397.544098235978, 11938.52245910154, 16742.079185644907, 16749.665006699797, 16767.077810720282, 16782.0, 16794.58013549671, 8629.180227878342, 8769.912300877037, 8824.117588241254, 8884.84497015384, 8961.59672814725, 8470.745877623682, 8531.488110713377, 8615.569221538897, 8639.481631102153, 8861.778455538533, 5079.085764562415, 5093.337866077461, 5098.770555325019, 5103.413107492686, 5136.126858434075, 2058.9794102059086, 2096.727425434684, 2116.957660846805, 2121.0, 2157.579272041968, 797.9880301795478, 974.0, 978.0806042320196, 998.9250806189565, 1138.8804175561613, 17719.379821706178, 18590.0, 19212.615747685246, 19270.90364548196, 19272.80727192738, 995.8655581496405, 1025.2823023883311, 1040.2718097331897, 1059.184427990443, 1115.358668765447, 10553.894461055292, 10937.398443085547, 10942.562297508011, 10979.890201098047, 11020.944895275472, 511683.0, 514501.0, 515554.0, 517305.0, 520144.0, 97909.0, 106135.0, 106243.0, 107942.0, 110548.4472577632, 1060.6658902445704, 1111.666500049998, 1137.533611219396, 1180.8405865208258, 1189.637160665988, 17321.393751218646, 17384.65230695404, 17545.210465529337, 17751.201195945956, 18130.818691812914, 41561.792191038854, 41935.580644194386, 41953.790231048646, 42922.35616465693, 43619.78190109029, 56565.151522726366, 57948.0, 58536.0, 59095.40904590899, 59914.40085599088, 8797.868031979653, 9009.729708108767, 9013.909860901307, 9042.63829446841, 9126.17864392208, 10653.9467302663, 12909.54816581415, 13002.869971300166, 13388.397522111723, 13821.0, 10807.891921081004, 10827.891721082688, 10953.233273670767, 11070.114390848874, 11640.126990475579, 10753.408562529135, 10823.837642435212, 11261.887381126084, 11306.717332066764, 11594.71013224676, 10973.561057557608, 10975.945120274348, 11034.724131896766, 11162.665120046411, 11408.315501069963, 9120.179183873483, 9185.311101667536, 9205.125513076247, 9424.858627120735, 9474.66838660671, 10294.485275736333, 11320.716982075513, 11370.94314528422, 11481.885181148082, 11754.823677644672], "value": 9585.953962504058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.7450433426316, 1533.2103966457273, 1722.4660355289861, 1808.5749848785542, 1853.5366158460386, 8888.600012999414, 9507.334486585942, 9509.667161649335, 9512.47681377524, 9519.048095190477, 8364.121767214447, 8645.956770216157, 9042.909570904289, 9281.257499400048, 9297.302702297338, 2529.4435224250683, 2539.174768200333, 2552.3236342369287, 2556.143691863228, 2557.3350928758473, 2437.914672986446, 2543.694756629207, 2566.358410397401, 2572.4083460804027, 2574.0476023871192, 2522.798176145904, 2532.4428625702317, 2554.7062087859854, 2556.8465892046443, 2558.769710726034, 2468.5680005999056, 2478.50429914017, 2488.4027833319965, 2500.249925022504, 2506.624006399055, 2470.6788117544606, 2473.777360037587, 2477.1206221791185, 2478.615814548748, 2506.6490691303234, 9535.856962145574, 9552.90447095527, 9602.039796020412, 9605.37565058269, 9620.470874101922, 5582.91625625616, 5685.943140568583, 5744.22452968848, 5749.166370876242, 5761.452661997104, 3351.279474912881, 3453.326601312745, 3521.6302288259876, 3533.134382076382, 3552.8401221945073, 1160.7040204747734, 1198.2930071258, 1210.334316126136, 1223.2232532341939, 1225.81612758087, 13327.0, 13367.331633418387, 13392.531261405902, 13410.664733381645, 13427.12723672958, 4880.1215781159735, 4937.5556199942175, 5049.0, 5198.064348417245, 5228.2941802856885, 11288.0, 11305.71735706614, 11332.830007549894, 11344.602938897264, 11352.035077018545, 5838.182654428383, 5842.474177324061, 5842.59101862874, 5852.736626851802, 5856.033754430557, 4661.976690116528, 4765.142274390573, 5287.524122828964, 5334.1732031535175, 5347.759350829222, 4296.892577685583, 4333.544977777351, 4416.668749843775, 4453.75504347264, 4460.1971645104195, 1314.8948084153244, 1315.7039666075057, 1348.6830594810212, 1359.232033900843, 1387.8959078069186, 859.4499520307008, 903.4218100415608, 1022.3150489172273, 1025.176093172456, 1028.922830787694, 6035.909461358083, 6127.295361033538, 6168.228971378578, 6322.051692246107, 6364.395382438661, 516.1509317173229, 516.4087120247295, 520.786477544205, 522.3914140026902, 529.6742503360483, 4338.197433474817, 4439.4006809080365, 4463.5313292103865, 4465.12929978651, 4528.320751887243, 11624.709382265512, 11962.043036557054, 12065.215760975509, 12082.57710980129, 12097.092718046182, 11142.721431964266, 11654.125940554492, 11667.76664466706, 11953.760924781629, 12005.039596832232, 8822.206001459772, 9001.909980900107, 9012.233960113464, 9040.231580315616, 9045.638174472948, 5956.463918247378, 6147.815565533041, 6400.679966001774, 6519.445847103049, 6658.900116498353, 10553.997370249814, 10920.399378034284, 10921.672349829518, 11076.11391088727, 11085.44572771342, 11063.834042489209, 11159.944200278947, 11263.380514071805, 11273.492692829006, 11284.605038823931, 7527.360174385239, 7736.8452630948195, 7752.883706744291, 7996.32031277348, 8179.959100204461, 8546.102659220693, 8863.157999989991, 9066.410683305829, 9246.95376523113, 9260.861087083564, 7898.921010790048, 8110.756677299691, 8200.220979006985, 8274.793130171553, 8508.787280317794, 8118.147594502729, 8133.227343402367, 8231.46495477792, 8293.377996650395, 8329.95835020821, 7364.0, 7481.177070522347, 7507.962460187664, 7590.430717696085, 7639.427042971911, 5219.0, 5280.788768449219, 5379.488948549882, 5472.2065300531995, 5542.140968149944, 5522.171674248895, 5569.164625306236, 5622.550195984393, 5673.574481913791, 5738.971305143447], "value": 4889.206952904892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.9896503104907, 354.2737388353874, 354.3126334910274, 354.6843309454585, 363.24626400219535, 5581.832545023645, 5597.804076857311, 5598.13228949513, 5646.520045796102, 5658.377578466366, 5422.779874528239, 5443.591730620209, 5529.0, 5555.611107222496, 5562.193481945115, 3946.565877753459, 4079.5925405735047, 4128.690348223895, 4271.914561708764, 4353.738775673454, 4313.870583882473, 4328.372386004027, 4330.285502892019, 4338.349247612852, 4350.173467041268, 4340.218760623086, 4340.479142502888, 4353.738775673454, 4354.847580334689, 4368.322909948947, 4095.488063992001, 4308.633766129883, 4325.307950727884, 4357.694961352707, 4364.585364390394, 3784.791836448994, 3798.6771124454453, 3805.46723458716, 3807.2385522895534, 3843.7117216208894, 5317.351620997503, 5343.851072019495, 5351.759170837322, 5365.865853353658, 5384.811531596414, 4808.735519546423, 4945.975270123627, 4968.254761785725, 4991.900161996739, 5000.874978125539, 5671.731307477001, 5684.204211410406, 5696.715164241813, 5735.4551317624755, 5747.482726554588, 4187.099773548671, 4191.455110835603, 4197.727147735387, 4200.991761977134, 4208.452901122865, 5819.533961328706, 5960.791372301992, 5968.612040217372, 5974.492168165668, 5995.250593675791, 4277.379779931893, 4281.293586558245, 4283.314669652903, 4283.4793648255045, 4292.356146577976, 4362.280223763107, 4371.622938774276, 4376.781160942004, 4383.978080109643, 4390.078083602415, 4817.9036419271415, 4964.230544265644, 4968.900621987612, 4975.387921341252, 5000.249962505581, 5714.200011998323, 5716.371199168088, 5727.570432217517, 5761.761221337432, 5814.534837213013, 1477.7709455034487, 1492.9552113436614, 1496.102338596855, 1502.4516051641071, 1506.9623259418383, 931.1340453378288, 949.3291806420749, 966.6520052780993, 971.6162115964296, 981.8723565936383, 4878.82876130518, 4906.607471402279, 4923.655344125877, 4951.232558953368, 4989.750512474362, 5070.0, 5108.3103780989095, 5122.462141475166, 5132.099604300758, 5142.640015198975, 4618.515055919147, 4864.902701946012, 4921.803127874917, 4935.555799977948, 4937.728424936591, 2743.0, 2744.8764805583796, 2760.986195069052, 2762.7651649610007, 2771.279467338466, 5272.521914700084, 5310.734463276821, 5329.680219186861, 5336.493033161843, 5346.946530534723, 5240.475952404805, 5255.947440525622, 5665.745041473144, 6043.3050199227655, 6083.8174854754425, 5830.475257226785, 6032.487238584682, 6043.48630366415, 6052.57631965767, 6057.545684073712, 5828.970855145697, 5931.3178984417345, 5961.582689211887, 5986.910196347146, 5991.940080599313, 5653.604247702621, 5885.705714714161, 5901.7639294427745, 5918.615290006136, 5944.881102377842, 6112.419320164578, 6182.258129024366, 6189.195404597373, 6196.535259855619, 6203.906941395973, 4278.935815962701, 4289.163613095417, 4311.655067594522, 4319.97840010798, 4327.307630779061, 5715.257016587817, 5882.676452794966, 5896.911546326723, 5897.882042359214, 5900.734466949083, 5795.362510123968, 5797.391273916178, 5808.925348810315, 5822.243108395882, 5822.970885145547, 5501.724913754377, 5767.365589785037, 5802.274715660543, 5816.243888294494, 5830.387809279964, 5717.685527295872, 5729.197912292366, 5793.15999180125, 5803.071508558611, 5810.796622118206, 5820.272465941757, 5825.50483208932, 5825.922204392201, 5832.475077243069, 5832.850260859089, 5512.0, 5654.15187721845, 5667.773289068391, 5679.176519404746, 5692.146178073156], "value": 4334.132755756861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.154319507712, 817.7791996161035, 820.3642177312582, 836.5900708652762, 927.8422668146414, 17764.200610972504, 18367.816321836777, 18474.630507389844, 18516.166772495253, 18548.72176917344, 16877.0, 17148.914255428735, 18058.187381567826, 18237.544061398472, 18237.7264341035, 740.5334639177311, 846.7205822078699, 865.7965378136133, 867.0679020053436, 868.622149365025, 749.1310080306845, 860.0797147052656, 878.2403221213636, 882.5102068352062, 883.7127933421632, 935.443411170356, 937.2501998401286, 938.4838338913604, 938.7371535969905, 941.5057095025098, 929.419113054341, 931.1479995803832, 931.2084727981196, 931.7344556801313, 933.5705575435308, 685.2428066986008, 687.5290426058166, 688.132952479874, 688.6900894597401, 689.152342618577, 16564.746591892213, 16597.0, 16670.16649167537, 16702.24839882196, 16805.15974201285, 15279.618009549738, 16273.755893661604, 16464.341426342933, 16489.752653710202, 16646.0, 12388.81416778749, 12974.0, 13494.52769153075, 13521.59435216945, 13619.387127579184, 4114.136031433402, 4180.28935081037, 4181.393697913785, 4269.530351661315, 4322.459692538433, 80093.79859302116, 80336.59831700863, 80636.0, 80675.0, 80852.19147808505, 11615.0, 11851.111166662362, 11919.344436055926, 12481.0, 12501.312427816558, 22509.662355064687, 22515.099396024303, 22546.21088261903, 22570.548589028127, 22591.322260331886, 8391.454555453876, 8760.561971901507, 8892.822143557092, 9284.350095493252, 9611.0, 5127.154019586801, 5340.759665815048, 5426.104692725735, 5442.700651464207, 5568.192612071308, 3853.344931361675, 4025.4364388985564, 4025.4565633639086, 4025.8590949316635, 4025.879223623296, 656.9638669873108, 665.1718610330073, 676.6413800685546, 679.6397909108181, 686.4611280145062, 656.1371796088129, 664.5248647217263, 677.295612562929, 692.3180667042959, 712.4727701500852, 9887.555060022316, 10237.157311683819, 10742.892571074346, 11198.982633231286, 11224.270422422516, 617.9907301390483, 650.3334082565408, 696.6760456387804, 701.3897908819359, 707.1090426063197, 5021.422536408287, 5073.34046573943, 5117.641765076483, 5135.87160320995, 5231.006108839309, 72129.0, 106673.0, 128791.35604321919, 128865.35567322103, 133040.33479832538, 107185.0, 107890.46054769676, 109413.35879961807, 109976.4014159153, 116064.0, 60968.695156524824, 78648.21351786409, 79902.6004869972, 80062.19937800548, 94570.52714736383, 9244.075592440971, 9454.14912657864, 9699.369540979813, 9937.65218217359, 10220.421863371375, 47764.7611761939, 50915.2362714567, 51574.74212629063, 54330.18504722353, 55276.40703354269, 71484.0, 74281.6285918567, 76766.61616691881, 78723.60638196772, 79087.60456197683, 13277.136986095873, 13845.446182153004, 13851.792223116863, 14054.78917816254, 14065.156090634595, 13804.17174969505, 14455.78316325277, 15049.699006020037, 16199.919000404923, 17069.91465042667, 14252.643683907985, 14595.197264150574, 15289.235538223267, 15366.462173823862, 15401.537953861402, 14721.411143554566, 14999.77500337474, 15352.923235383752, 16020.958637688978, 16493.0, 11549.769004620028, 13943.0, 14131.0, 14207.786883196553, 14297.35661895238, 12074.335911524951, 12689.555865544662, 12701.8094728581, 12895.742085158432, 13128.146670466389, 11931.164818462625, 12322.32227227511, 12487.812682809947, 12678.556250531186, 12983.0], "value": 8102.001602271682, "unit": "score"}], "name": "dromaeo_css", "value": 7574.684170747553, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 115.0, 115.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 120.0, 153.0, 123.0, 118.0, 119.0, 119.0, 148.0, 123.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 168.0, 160.0, 162.0, 162.0, 161.0, 160.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 108.0, 76.0, 76.0, 76.0, 76.0, 100.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 122.0, 84.0, 84.0, 82.0, 99.0, 82.0, 82.0, 118.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 97.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 49.0, 51.0, 50.0, 50.0, 49.0, 49.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 61.0, 59.0, 59.0, 61.0, 61.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 73.0, 74.0, 75.0, 73.0, 86.0, 74.0, 80.0, 73.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 136.0, 124.0, 129.0, 122.0, 130.0, 120.0, 121.0, 163.0, 138.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 155.0, 153.0, 157.0, 155.0, 154.0, 152.0, 152.0, 155.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 62.0, 63.0, 63.0, 61.0, 62.0, 62.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1469.9, "alertThreshold": 2.0}]} 12:43:19 INFO - Return code: 0 12:43:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:43:19 INFO - # TBPL SUCCESS # 12:43:19 INFO - Running post-action listener: _resource_record_post_action 12:43:19 INFO - [mozharness: 2016-11-14 20:43:19.810555Z] Finished run-tests step (success) 12:43:19 INFO - Running post-run listener: _resource_record_post_run 12:43:19 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 309370880; Read time: 0; Write time: 208080 12:43:19 INFO - TinderboxPrint: CPU usage
12.9% 12:43:19 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:43:19 INFO - TinderboxPrint: I/O write bytes / time
309,370,880 / 208,080 12:43:19 INFO - TinderboxPrint: CPU idle
8,239.8 (87.0%) 12:43:19 INFO - TinderboxPrint: CPU user
1,185.7 (12.5%) 12:43:19 INFO - TinderboxPrint: Swap in / out
0 / 0 12:43:19 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100315136; Read time: 0; Write time: 84492 12:43:19 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 209055744; Read time: 0; Write time: 123588 12:43:20 INFO - Running post-run listener: _upload_blobber_files 12:43:20 INFO - Blob upload gear active. 12:43:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:20 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:43:20 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:43:20 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:43:20 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:43:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:43:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:25 INFO - (blobuploader) - INFO - Done attempting. 12:43:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:43:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:43:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:26 INFO - (blobuploader) - INFO - Done attempting. 12:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:43:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:27 INFO - (blobuploader) - INFO - Done attempting. 12:43:27 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:27 INFO - Return code: 0 12:43:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7b8bfc599cfc2f4df65989c04068bdd15716141a8a5e548c4737c6b901fe4b3c5c13b464d32307245f31341538a9772384e56ec100e02154e52a33943485ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd03ab4e4d38a80d2aea14b749094553013b4b7ff88ffa5fb8c040db66f5e43346e4110090a5c4c18e9f769b702a23c4aa9ac2346136ca07b587420d9425b6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f48e51f8e606e1649b74fb6bcc921dc6ea090f9e7fd8207cc3d311f98586b6ddcb35e1cc1b35b02f1e6c78aab411445216417d65cae27c8f2b3b626c594aeba"} 12:43:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:27 INFO - Contents: 12:43:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7b8bfc599cfc2f4df65989c04068bdd15716141a8a5e548c4737c6b901fe4b3c5c13b464d32307245f31341538a9772384e56ec100e02154e52a33943485ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd03ab4e4d38a80d2aea14b749094553013b4b7ff88ffa5fb8c040db66f5e43346e4110090a5c4c18e9f769b702a23c4aa9ac2346136ca07b587420d9425b6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f48e51f8e606e1649b74fb6bcc921dc6ea090f9e7fd8207cc3d311f98586b6ddcb35e1cc1b35b02f1e6c78aab411445216417d65cae27c8f2b3b626c594aeba"} 12:43:27 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:27 INFO - Copying logs to upload dir... 12:43:27 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.585525 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-14 12:43:27.370454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:43:27.374025) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7b8bfc599cfc2f4df65989c04068bdd15716141a8a5e548c4737c6b901fe4b3c5c13b464d32307245f31341538a9772384e56ec100e02154e52a33943485ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd03ab4e4d38a80d2aea14b749094553013b4b7ff88ffa5fb8c040db66f5e43346e4110090a5c4c18e9f769b702a23c4aa9ac2346136ca07b587420d9425b6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f48e51f8e606e1649b74fb6bcc921dc6ea090f9e7fd8207cc3d311f98586b6ddcb35e1cc1b35b02f1e6c78aab411445216417d65cae27c8f2b3b626c594aeba"} build_url:https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011855 build_url: 'https://queue.taskcluster.net/v1/task/WtuqIdHwR4aDkpnraba8tA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7b8bfc599cfc2f4df65989c04068bdd15716141a8a5e548c4737c6b901fe4b3c5c13b464d32307245f31341538a9772384e56ec100e02154e52a33943485ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd03ab4e4d38a80d2aea14b749094553013b4b7ff88ffa5fb8c040db66f5e43346e4110090a5c4c18e9f769b702a23c4aa9ac2346136ca07b587420d9425b6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f48e51f8e606e1649b74fb6bcc921dc6ea090f9e7fd8207cc3d311f98586b6ddcb35e1cc1b35b02f1e6c78aab411445216417d65cae27c8f2b3b626c594aeba"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:43:27.400063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:43:27.400395) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1479154974.398228-1639578451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:43:27.483478) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:43:27.483808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:43:27.484366) ========= ========= Total master_lag: 0.17 =========