builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1477310687.15 results: success (0) buildid: 20161024021422 builduid: 31df18ef723e4d4c9c4f5879544a06ca revision: 4e2043b472d20feba39a349b220742f463983a67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.154916) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.155727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.156010) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.475426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.475949) ========= 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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 05:04:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 54.5K=0.2s 2016-10-24 05:04:49 (54.5 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.376066 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.941974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:47.942545) ========= 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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021651 ========= master_lag: 0.19 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.154541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.154885) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4e2043b472d20feba39a349b220742f463983a67 --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-aurora --rev 4e2043b472d20feba39a349b220742f463983a67 --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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 05:04:49,812 truncating revision to first 12 chars 2016-10-24 05:04:49,812 Setting DEBUG logging. 2016-10-24 05:04:49,812 attempt 1/10 2016-10-24 05:04:49,812 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4e2043b472d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 05:04:50,134 unpacking tar archive at: mozilla-aurora-4e2043b472d2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.635222 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.879004) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.879329) ========= script_repo_revision: 4e2043b472d20feba39a349b220742f463983a67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.879756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:48.880018) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 05:04:49.185527) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-10-24 05:04:49.185912) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:04:50 INFO - MultiFileLogger online at 20161024 05:04:50 in /builds/slave/test 05:04:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:04:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:50 INFO - {'append_to_log': False, 05:04:50 INFO - 'base_work_dir': '/builds/slave/test', 05:04:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:04:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:50 INFO - 'branch': 'Mozilla-Aurora', 05:04:50 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:50 INFO - 'config_files': ('talos/linux_config.py',), 05:04:50 INFO - 'default_actions': ('clobber', 05:04:50 INFO - 'read-buildbot-config', 05:04:50 INFO - 'download-and-extract', 05:04:50 INFO - 'populate-webroot', 05:04:50 INFO - 'create-virtualenv', 05:04:50 INFO - 'install', 05:04:50 INFO - 'run-tests'), 05:04:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:50 INFO - 'download_minidump_stackwalk': True, 05:04:50 INFO - 'download_symbols': 'ondemand', 05:04:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:50 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:50 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:50 INFO - 'installer_path': 'installer.exe', 05:04:50 INFO - 'log_level': 'info', 05:04:50 INFO - 'log_name': 'talos', 05:04:50 INFO - 'log_to_console': True, 05:04:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:04:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:04:50 INFO - 'opt_config_files': (), 05:04:50 INFO - 'pip_index': False, 05:04:50 INFO - 'sps_profile': False, 05:04:50 INFO - 'sps_profile_interval': 0, 05:04:50 INFO - 'suite': 'dromaeojs', 05:04:50 INFO - 'system_bits': '32', 05:04:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:50 INFO - 'title': 'talos-linux64-ix-053', 05:04:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:50 INFO - 'use_talos_json': True, 05:04:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:50 INFO - 'work_dir': 'build'} 05:04:50 INFO - [mozharness: 2016-10-24 12:04:50.944030Z] Running clobber step. 05:04:50 INFO - Running pre-action listener: _resource_record_pre_action 05:04:50 INFO - Running main action method: clobber 05:04:50 INFO - rmtree: /builds/slave/test/build 05:04:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:51 INFO - Running post-action listener: _resource_record_post_action 05:04:51 INFO - [mozharness: 2016-10-24 12:04:51.930155Z] Finished clobber step (success) 05:04:51 INFO - [mozharness: 2016-10-24 12:04:51.930254Z] Running read-buildbot-config step. 05:04:51 INFO - Running pre-action listener: _resource_record_pre_action 05:04:51 INFO - Running main action method: read_buildbot_config 05:04:51 INFO - Using buildbot properties: 05:04:51 INFO - { 05:04:51 INFO - "project": "", 05:04:51 INFO - "product": "firefox", 05:04:51 INFO - "script_repo_revision": "production", 05:04:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:04:51 INFO - "repository": "", 05:04:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:04:51 INFO - "buildid": "20161024021422", 05:04:51 INFO - "pgo_build": "True", 05:04:51 INFO - "basedir": "/builds/slave/test", 05:04:51 INFO - "buildnumber": 826, 05:04:51 INFO - "slavename": "talos-linux64-ix-053", 05:04:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:04:51 INFO - "platform": "ubuntu64_hw", 05:04:51 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:04:51 INFO - "branch": "mozilla-aurora", 05:04:51 INFO - "repo_path": "releases/mozilla-aurora", 05:04:51 INFO - "revision": "4e2043b472d20feba39a349b220742f463983a67", 05:04:51 INFO - "stage_platform": "linux64-pgo", 05:04:51 INFO - "builduid": "31df18ef723e4d4c9c4f5879544a06ca", 05:04:51 INFO - "slavebuilddir": "test" 05:04:51 INFO - } 05:04:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 05:04:51 INFO - Running post-action listener: _resource_record_post_action 05:04:51 INFO - [mozharness: 2016-10-24 12:04:51.932712Z] Finished read-buildbot-config step (success) 05:04:51 INFO - [mozharness: 2016-10-24 12:04:51.932801Z] Running download-and-extract step. 05:04:51 INFO - Running pre-action listener: _resource_record_pre_action 05:04:51 INFO - Running main action method: download_and_extract 05:04:51 INFO - mkdir: /builds/slave/test/build/tests 05:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:51 INFO - trying https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:04:51 INFO - Downloading https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:04:52 INFO - Downloaded 1361 bytes. 05:04:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:04:52 INFO - Using the following test package requirements: 05:04:52 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:04:52 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:04:52 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:04:52 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'jsshell-linux-x86_64.zip'], 05:04:52 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:04:52 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:04:52 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:04:52 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:04:52 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:04:52 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:04:52 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:04:52 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:04:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:04:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:04:52 INFO - Fetch https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:04:54 INFO - Expected file size: 21907188 05:05:04 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:05:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:05:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:05:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:05:05 INFO - Expected file size: 21907188 05:05:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 05:05:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:05:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 05:05:11 INFO - Expected file size: 14414081 05:05:11 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')]} 05:05:11 INFO - trying https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:05:19 INFO - Downloaded 64014179 bytes. 05:05:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:05:19 INFO - mkdir: /builds/slave/test/properties 05:05:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:19 INFO - Contents: 05:05:19 INFO - build_url:https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:05:19 INFO - Running post-action listener: _resource_record_post_action 05:05:19 INFO - Running post-action listener: set_extra_try_arguments 05:05:19 INFO - [mozharness: 2016-10-24 12:05:19.673337Z] Finished download-and-extract step (success) 05:05:19 INFO - [mozharness: 2016-10-24 12:05:19.673566Z] Running populate-webroot step. 05:05:19 INFO - Running pre-action listener: _resource_record_pre_action 05:05:19 INFO - Running main action method: populate_webroot 05:05:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:19 INFO - '--tppagecycles', 05:05:19 INFO - '7'], 05:05:19 INFO - 'tests': ['tsvgm']}}, 05:05:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['damp', 'tps']}, 05:05:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'tests': ['damp', 'tps']}, 05:05:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['dromaeo_dom']}, 05:05:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['basic_compositor_video']}, 05:05:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['a11yr', 05:05:19 INFO - 'ts_paint', 05:05:19 INFO - 'tpaint', 05:05:19 INFO - 'sessionrestore', 05:05:19 INFO - 'sessionrestore_no_auto_restore', 05:05:19 INFO - 'tabpaint']}, 05:05:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['tsvgx', 05:05:19 INFO - 'tsvgr_opacity', 05:05:19 INFO - 'tart', 05:05:19 INFO - 'tscrollx', 05:05:19 INFO - 'cart']}, 05:05:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:19 INFO - 'tsvgr_opacity', 05:05:19 INFO - 'tart', 05:05:19 INFO - 'tscrollx', 05:05:19 INFO - 'cart']}, 05:05:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'talos_options': ['--disable-e10s'], 05:05:19 INFO - 'tests': ['tp5o']}, 05:05:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'tests': ['tp5o']}, 05:05:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'talos_options': ['--disable-e10s', 05:05:19 INFO - '--xperf_path', 05:05:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:19 INFO - 'tests': ['tp5n']}, 05:05:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:19 INFO - 'talos_options': ['--xperf_path', 05:05:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:19 INFO - 'tests': ['tp5n']}}, 05:05:19 INFO - 'talos.zip': {'path': '', 05:05:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:19 INFO - Running post-action listener: _resource_record_post_action 05:05:19 INFO - [mozharness: 2016-10-24 12:05:19.690449Z] Finished populate-webroot step (success) 05:05:19 INFO - [mozharness: 2016-10-24 12:05:19.690537Z] Running create-virtualenv step. 05:05:19 INFO - Running pre-action listener: _resource_record_pre_action 05:05:19 INFO - Running main action method: create_virtualenv 05:05:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:19 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 05:05:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:19 INFO - Using real prefix '/usr' 05:05:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:22 INFO - Installing pip.................done. 05:05:22 INFO - Return code: 0 05:05:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:22 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 05:05:22 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 05:05:22 INFO - Using env: {'DISPLAY': ':0', 05:05:22 INFO - 'HOME': '/home/cltbld', 05:05:22 INFO - 'LANG': 'en_US.UTF-8', 05:05:22 INFO - 'LANGUAGE': 'en_US:en', 05:05:22 INFO - 'LOGNAME': 'cltbld', 05:05:22 INFO - 'MAIL': '/var/mail/cltbld', 05:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:22 INFO - 'MOZ_NO_REMOTE': '1', 05:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:22 INFO - 'NO_EM_RESTART': '1', 05:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:22 INFO - 'PWD': '/builds/slave/test', 05:05:22 INFO - 'SHELL': '/bin/bash', 05:05:22 INFO - 'SHLVL': '1', 05:05:22 INFO - 'TERM': 'linux', 05:05:22 INFO - 'TMOUT': '86400', 05:05:22 INFO - 'USER': 'cltbld', 05:05:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:22 INFO - '_': '/tools/buildbot/bin/python'} 05:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:22 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 05:05:22 INFO - Cleaning up... 05:05:22 INFO - Return code: 0 05:05:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:22 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 05:05:22 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 05:05:22 INFO - Using env: {'DISPLAY': ':0', 05:05:22 INFO - 'HOME': '/home/cltbld', 05:05:22 INFO - 'LANG': 'en_US.UTF-8', 05:05:22 INFO - 'LANGUAGE': 'en_US:en', 05:05:22 INFO - 'LOGNAME': 'cltbld', 05:05:22 INFO - 'MAIL': '/var/mail/cltbld', 05:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:22 INFO - 'MOZ_NO_REMOTE': '1', 05:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:22 INFO - 'NO_EM_RESTART': '1', 05:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:22 INFO - 'PWD': '/builds/slave/test', 05:05:22 INFO - 'SHELL': '/bin/bash', 05:05:22 INFO - 'SHLVL': '1', 05:05:22 INFO - 'TERM': 'linux', 05:05:22 INFO - 'TMOUT': '86400', 05:05:22 INFO - 'USER': 'cltbld', 05:05:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:22 INFO - '_': '/tools/buildbot/bin/python'} 05:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:22 INFO - Downloading/unpacking psutil>=3.1.1 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:26 INFO - Installing collected packages: psutil 05:05:26 INFO - Running setup.py install for psutil 05:05:26 INFO - building 'psutil._psutil_linux' extension 05:05:26 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 05:05:26 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 05:05:26 INFO - building 'psutil._psutil_posix' extension 05:05:26 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 05:05:26 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 05:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:26 INFO - Successfully installed psutil 05:05:26 INFO - Cleaning up... 05:05:26 INFO - Return code: 0 05:05:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:26 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')]} 05:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 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')]} 05:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:26 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:26 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 05:05:26 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 05:05:26 INFO - Using env: {'DISPLAY': ':0', 05:05:26 INFO - 'HOME': '/home/cltbld', 05:05:26 INFO - 'LANG': 'en_US.UTF-8', 05:05:26 INFO - 'LANGUAGE': 'en_US:en', 05:05:26 INFO - 'LOGNAME': 'cltbld', 05:05:26 INFO - 'MAIL': '/var/mail/cltbld', 05:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:26 INFO - 'MOZ_NO_REMOTE': '1', 05:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:26 INFO - 'NO_EM_RESTART': '1', 05:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:26 INFO - 'PWD': '/builds/slave/test', 05:05:26 INFO - 'SHELL': '/bin/bash', 05:05:26 INFO - 'SHLVL': '1', 05:05:26 INFO - 'TERM': 'linux', 05:05:26 INFO - 'TMOUT': '86400', 05:05:26 INFO - 'USER': 'cltbld', 05:05:26 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:26 INFO - '_': '/tools/buildbot/bin/python'} 05:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:27 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 05:05:27 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 05:05:27 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 05:05:27 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 05:05:27 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 05:05:27 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 05:05:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:29 INFO - Installing collected packages: mozsystemmonitor 05:05:29 INFO - Running setup.py install for mozsystemmonitor 05:05:29 INFO - Successfully installed mozsystemmonitor 05:05:29 INFO - Cleaning up... 05:05:29 INFO - Return code: 0 05:05:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:05:29 INFO - Using env: {'DISPLAY': ':0', 05:05:29 INFO - 'HOME': '/home/cltbld', 05:05:29 INFO - 'LANG': 'en_US.UTF-8', 05:05:29 INFO - 'LANGUAGE': 'en_US:en', 05:05:29 INFO - 'LOGNAME': 'cltbld', 05:05:29 INFO - 'MAIL': '/var/mail/cltbld', 05:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:29 INFO - 'MOZ_NO_REMOTE': '1', 05:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:29 INFO - 'NO_EM_RESTART': '1', 05:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:29 INFO - 'PWD': '/builds/slave/test', 05:05:29 INFO - 'SHELL': '/bin/bash', 05:05:29 INFO - 'SHLVL': '1', 05:05:29 INFO - 'TERM': 'linux', 05:05:29 INFO - 'TMOUT': '86400', 05:05:29 INFO - 'USER': 'cltbld', 05:05:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:29 INFO - '_': '/tools/buildbot/bin/python'} 05:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:29 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 05:05:29 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 05:05:29 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 05:05:29 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 05:05:29 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 05:05:29 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 05:05:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:32 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 05:05:32 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 05:05:32 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 05:05:32 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 05:05:32 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 05:05:32 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 05:05:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:33 INFO - warning: no files found matching '*.txt' 05:05:33 INFO - no previously-included directories found matching 'build' 05:05:33 INFO - no previously-included directories found matching 'dist' 05:05:33 INFO - no previously-included directories found matching '.git*' 05:05:33 INFO - Installing collected packages: jsonschema, functools32 05:05:33 INFO - Running setup.py install for functools32 05:05:33 INFO - warning: no files found matching '*.txt' 05:05:33 INFO - no previously-included directories found matching 'build' 05:05:33 INFO - no previously-included directories found matching 'dist' 05:05:33 INFO - no previously-included directories found matching '.git*' 05:05:33 INFO - Successfully installed jsonschema functools32 05:05:33 INFO - Cleaning up... 05:05:33 INFO - Return code: 0 05:05:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05:33 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')]} 05:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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')]} 05:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:33 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 05:05:33 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 05:05:33 INFO - Using env: {'DISPLAY': ':0', 05:05:33 INFO - 'HOME': '/home/cltbld', 05:05:33 INFO - 'LANG': 'en_US.UTF-8', 05:05:33 INFO - 'LANGUAGE': 'en_US:en', 05:05:33 INFO - 'LOGNAME': 'cltbld', 05:05:33 INFO - 'MAIL': '/var/mail/cltbld', 05:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:33 INFO - 'MOZ_NO_REMOTE': '1', 05:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:33 INFO - 'NO_EM_RESTART': '1', 05:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:33 INFO - 'PWD': '/builds/slave/test', 05:05:33 INFO - 'SHELL': '/bin/bash', 05:05:33 INFO - 'SHLVL': '1', 05:05:33 INFO - 'TERM': 'linux', 05:05:33 INFO - 'TMOUT': '86400', 05:05:33 INFO - 'USER': 'cltbld', 05:05:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:33 INFO - '_': '/tools/buildbot/bin/python'} 05:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:33 INFO - Cleaning up... 05:05:33 INFO - Return code: 0 05:05:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:33 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')]} 05:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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')]} 05:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:33 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:33 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 05:05:33 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 05:05:33 INFO - Using env: {'DISPLAY': ':0', 05:05:33 INFO - 'HOME': '/home/cltbld', 05:05:33 INFO - 'LANG': 'en_US.UTF-8', 05:05:33 INFO - 'LANGUAGE': 'en_US:en', 05:05:33 INFO - 'LOGNAME': 'cltbld', 05:05:33 INFO - 'MAIL': '/var/mail/cltbld', 05:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:33 INFO - 'MOZ_NO_REMOTE': '1', 05:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:33 INFO - 'NO_EM_RESTART': '1', 05:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:33 INFO - 'PWD': '/builds/slave/test', 05:05:33 INFO - 'SHELL': '/bin/bash', 05:05:33 INFO - 'SHLVL': '1', 05:05:33 INFO - 'TERM': 'linux', 05:05:33 INFO - 'TMOUT': '86400', 05:05:33 INFO - 'USER': 'cltbld', 05:05:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:33 INFO - '_': '/tools/buildbot/bin/python'} 05:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:33 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:33 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 05:05:33 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 05:05:33 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 05:05:33 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 05:05:33 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 05:05:33 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 05:05:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:39 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 05:05:39 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 05:05:39 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 05:05:39 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 05:05:39 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 05:05:39 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 05:05:39 INFO - Downloading docopt-0.6.1.tar.gz 05:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:39 INFO - Running setup.py install for blobuploader 05:05:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:39 INFO - Running setup.py install for requests 05:05:39 INFO - Running setup.py install for docopt 05:05:39 INFO - Successfully installed blobuploader requests docopt 05:05:39 INFO - Cleaning up... 05:05:40 INFO - Return code: 0 05:05:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:40 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')]} 05:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:40 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')]} 05:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:40 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:40 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 05:05:40 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 05:05:40 INFO - Using env: {'DISPLAY': ':0', 05:05:40 INFO - 'HOME': '/home/cltbld', 05:05:40 INFO - 'LANG': 'en_US.UTF-8', 05:05:40 INFO - 'LANGUAGE': 'en_US:en', 05:05:40 INFO - 'LOGNAME': 'cltbld', 05:05:40 INFO - 'MAIL': '/var/mail/cltbld', 05:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:40 INFO - 'MOZ_NO_REMOTE': '1', 05:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:40 INFO - 'NO_EM_RESTART': '1', 05:05:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:40 INFO - 'PWD': '/builds/slave/test', 05:05:40 INFO - 'SHELL': '/bin/bash', 05:05:40 INFO - 'SHLVL': '1', 05:05:40 INFO - 'TERM': 'linux', 05:05:40 INFO - 'TMOUT': '86400', 05:05:40 INFO - 'USER': 'cltbld', 05:05:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:40 INFO - '_': '/tools/buildbot/bin/python'} 05:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:40 INFO - Running setup.py (path:/tmp/pip-xz2Y3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:40 INFO - Running setup.py (path:/tmp/pip-5lsYJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:40 INFO - Running setup.py (path:/tmp/pip-N130Zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:40 INFO - Running setup.py (path:/tmp/pip-R3xJaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:40 INFO - Running setup.py (path:/tmp/pip-3bBxLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:40 INFO - Running setup.py (path:/tmp/pip-_YTbAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:40 INFO - Running setup.py (path:/tmp/pip-36bHQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:40 INFO - Running setup.py (path:/tmp/pip-Y5dZ2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:40 INFO - Running setup.py (path:/tmp/pip-YpZSGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:40 INFO - Running setup.py (path:/tmp/pip-ju1lZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:40 INFO - Running setup.py (path:/tmp/pip-BCsUxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:41 INFO - Running setup.py (path:/tmp/pip-OP_od9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:41 INFO - Running setup.py (path:/tmp/pip-_TNS5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:41 INFO - Running setup.py (path:/tmp/pip-q2fosx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:41 INFO - Running setup.py (path:/tmp/pip-CApgMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:41 INFO - Running setup.py (path:/tmp/pip-loyYNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:41 INFO - Running setup.py (path:/tmp/pip-Won1BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:41 INFO - Running setup.py install for manifestparser 05:05:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:41 INFO - Running setup.py install for mozcrash 05:05:41 INFO - Running setup.py install for mozdebug 05:05:41 INFO - Running setup.py install for mozdevice 05:05:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:41 INFO - Running setup.py install for mozfile 05:05:41 INFO - Running setup.py install for mozhttpd 05:05:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozinfo 05:05:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozInstall 05:05:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozleak 05:05:42 INFO - Running setup.py install for mozlog 05:05:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for moznetwork 05:05:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozprocess 05:05:42 INFO - Running setup.py install for mozprofile 05:05:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozrunner 05:05:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:42 INFO - Running setup.py install for mozscreenshot 05:05:43 INFO - Running setup.py install for moztest 05:05:43 INFO - Running setup.py install for mozversion 05:05:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:43 INFO - Cleaning up... 05:05:43 INFO - Return code: 0 05:05:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:43 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:43 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 05:05:43 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 05:05:43 INFO - Using env: {'DISPLAY': ':0', 05:05:43 INFO - 'HOME': '/home/cltbld', 05:05:43 INFO - 'LANG': 'en_US.UTF-8', 05:05:43 INFO - 'LANGUAGE': 'en_US:en', 05:05:43 INFO - 'LOGNAME': 'cltbld', 05:05:43 INFO - 'MAIL': '/var/mail/cltbld', 05:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:43 INFO - 'MOZ_NO_REMOTE': '1', 05:05:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:43 INFO - 'NO_EM_RESTART': '1', 05:05:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:43 INFO - 'PWD': '/builds/slave/test', 05:05:43 INFO - 'SHELL': '/bin/bash', 05:05:43 INFO - 'SHLVL': '1', 05:05:43 INFO - 'TERM': 'linux', 05:05:43 INFO - 'TMOUT': '86400', 05:05:43 INFO - 'USER': 'cltbld', 05:05:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:43 INFO - '_': '/tools/buildbot/bin/python'} 05:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:43 INFO - Running setup.py (path:/tmp/pip-H3DlwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:43 INFO - Running setup.py (path:/tmp/pip-g_Cm0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:43 INFO - Running setup.py (path:/tmp/pip-BiowXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:43 INFO - Running setup.py (path:/tmp/pip-lkMhun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:43 INFO - Running setup.py (path:/tmp/pip-DJ5fs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:43 INFO - Running setup.py (path:/tmp/pip-WeNCDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:43 INFO - Running setup.py (path:/tmp/pip-J6k9MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:43 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)) 05:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:43 INFO - Running setup.py (path:/tmp/pip-8uAljc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:44 INFO - Running setup.py (path:/tmp/pip-WbXiQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:44 INFO - Running setup.py (path:/tmp/pip-JTsbIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:44 INFO - Running setup.py (path:/tmp/pip-Dap2pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:44 INFO - Running setup.py (path:/tmp/pip-2THkga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:44 INFO - Running setup.py (path:/tmp/pip-NHpvxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:44 INFO - Running setup.py (path:/tmp/pip-Wykucr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:44 INFO - Running setup.py (path:/tmp/pip-tUDO34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:44 INFO - Running setup.py (path:/tmp/pip-FqLR3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:44 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)) 05:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:44 INFO - Running setup.py (path:/tmp/pip-OX6O4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:46 INFO - Downloading blessings-1.6.tar.gz 05:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:46 INFO - Installing collected packages: blessings 05:05:46 INFO - Running setup.py install for blessings 05:05:46 INFO - Successfully installed blessings 05:05:46 INFO - Cleaning up... 05:05:47 INFO - Return code: 0 05:05:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:47 INFO - Reading from file tmpfile_stdout 05:05:47 INFO - Current package versions: 05:05:47 INFO - argparse == 1.2.1 05:05:47 INFO - blessings == 1.6 05:05:47 INFO - blobuploader == 1.2.4 05:05:47 INFO - docopt == 0.6.1 05:05:47 INFO - functools32 == 3.2.3-2 05:05:47 INFO - jsonschema == 2.5.1 05:05:47 INFO - manifestparser == 1.1 05:05:47 INFO - mozInstall == 1.12 05:05:47 INFO - mozcrash == 0.17 05:05:47 INFO - mozdebug == 0.1 05:05:47 INFO - mozdevice == 0.48 05:05:47 INFO - mozfile == 1.2 05:05:47 INFO - mozhttpd == 0.7 05:05:47 INFO - mozinfo == 0.9 05:05:47 INFO - mozleak == 0.1 05:05:47 INFO - mozlog == 3.4 05:05:47 INFO - moznetwork == 0.27 05:05:47 INFO - mozprocess == 0.23 05:05:47 INFO - mozprofile == 0.28 05:05:47 INFO - mozrunner == 6.12 05:05:47 INFO - mozscreenshot == 0.1 05:05:47 INFO - mozsystemmonitor == 0.3 05:05:47 INFO - moztest == 0.7 05:05:47 INFO - mozversion == 1.4 05:05:47 INFO - psutil == 3.1.1 05:05:47 INFO - requests == 1.2.3 05:05:47 INFO - wsgiref == 0.1.2 05:05:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:47 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 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:47 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 05:05:47 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 05:05:47 INFO - Using env: {'DISPLAY': ':0', 05:05:47 INFO - 'HOME': '/home/cltbld', 05:05:47 INFO - 'LANG': 'en_US.UTF-8', 05:05:47 INFO - 'LANGUAGE': 'en_US:en', 05:05:47 INFO - 'LOGNAME': 'cltbld', 05:05:47 INFO - 'MAIL': '/var/mail/cltbld', 05:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:47 INFO - 'MOZ_NO_REMOTE': '1', 05:05:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:47 INFO - 'NO_EM_RESTART': '1', 05:05:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:47 INFO - 'PWD': '/builds/slave/test', 05:05:47 INFO - 'SHELL': '/bin/bash', 05:05:47 INFO - 'SHLVL': '1', 05:05:47 INFO - 'TERM': 'linux', 05:05:47 INFO - 'TMOUT': '86400', 05:05:47 INFO - 'USER': 'cltbld', 05:05:47 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:47 INFO - '_': '/tools/buildbot/bin/python'} 05:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 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)) 05:05:47 INFO - Cleaning up... 05:05:47 INFO - Return code: 0 05:05:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f938f808f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, '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': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', '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 05:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:05:47 INFO - Using env: {'DISPLAY': ':0', 05:05:47 INFO - 'HOME': '/home/cltbld', 05:05:47 INFO - 'LANG': 'en_US.UTF-8', 05:05:47 INFO - 'LANGUAGE': 'en_US:en', 05:05:47 INFO - 'LOGNAME': 'cltbld', 05:05:47 INFO - 'MAIL': '/var/mail/cltbld', 05:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:47 INFO - 'MOZ_NO_REMOTE': '1', 05:05:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:47 INFO - 'NO_EM_RESTART': '1', 05:05:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:47 INFO - 'PWD': '/builds/slave/test', 05:05:47 INFO - 'SHELL': '/bin/bash', 05:05:47 INFO - 'SHLVL': '1', 05:05:47 INFO - 'TERM': 'linux', 05:05:47 INFO - 'TMOUT': '86400', 05:05:47 INFO - 'USER': 'cltbld', 05:05:47 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:47 INFO - '_': '/tools/buildbot/bin/python'} 05:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:47 INFO - Cleaning up... 05:05:47 INFO - Return code: 0 05:05:47 INFO - Running post-action listener: _resource_record_post_action 05:05:47 INFO - Running post-action listener: _start_resource_monitoring 05:05:47 INFO - Starting resource monitoring. 05:05:47 INFO - [mozharness: 2016-10-24 12:05:47.740476Z] Finished create-virtualenv step (success) 05:05:47 INFO - [mozharness: 2016-10-24 12:05:47.740926Z] Running install step. 05:05:47 INFO - Running pre-action listener: _resource_record_pre_action 05:05:47 INFO - Running main action method: install 05:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:47 INFO - Reading from file tmpfile_stdout 05:05:47 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:48 INFO - Reading from file tmpfile_stdout 05:05:48 INFO - Output received: 05:05:48 INFO - Usage: mozinstall [options] installer 05:05:48 INFO - Options: 05:05:48 INFO - -h, --help show this help message and exit 05:05:48 INFO - -d DEST, --destination=DEST 05:05:48 INFO - Directory to install application into. [default: 05:05:48 INFO - "/builds/slave/test"] 05:05:48 INFO - --app=APP Application being installed. [default: firefox] 05:05:48 INFO - mkdir: /builds/slave/test/build/application 05:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:06:04 INFO - Reading from file tmpfile_stdout 05:06:04 INFO - Output received: 05:06:04 INFO - /builds/slave/test/build/application/firefox/firefox 05:06:04 INFO - Running post-action listener: _resource_record_post_action 05:06:04 INFO - [mozharness: 2016-10-24 12:06:04.003251Z] Finished install step (success) 05:06:04 INFO - [mozharness: 2016-10-24 12:06:04.003501Z] Running run-tests step. 05:06:04 INFO - Running pre-action listener: _resource_record_pre_action 05:06:04 INFO - Running main action method: run_tests 05:06:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:04 INFO - Python 2.7.3 05:06:04 INFO - Return code: 0 05:06:04 INFO - grabbing minidump binary from tooltool 05:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:04 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 0x22f3f60>, '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 0x22f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22f51d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:04 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 05:06:04 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 05:06:04 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 05:06:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:04 INFO - Return code: 0 05:06:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:06:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:06:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 05:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 05:06:04 INFO - Using env: {'DISPLAY': ':0', 05:06:04 INFO - 'HOME': '/home/cltbld', 05:06:04 INFO - 'LANG': 'en_US.UTF-8', 05:06:04 INFO - 'LANGUAGE': 'en_US:en', 05:06:04 INFO - 'LOGNAME': 'cltbld', 05:06:04 INFO - 'MAIL': '/var/mail/cltbld', 05:06:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:04 INFO - 'MOZ_NO_REMOTE': '1', 05:06:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:04 INFO - 'NO_EM_RESTART': '1', 05:06:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:04 INFO - 'PWD': '/builds/slave/test', 05:06:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:04 INFO - 'SHELL': '/bin/bash', 05:06:04 INFO - 'SHLVL': '1', 05:06:04 INFO - 'TERM': 'linux', 05:06:04 INFO - 'TMOUT': '86400', 05:06:04 INFO - 'USER': 'cltbld', 05:06:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968', 05:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:04 INFO - '_': '/tools/buildbot/bin/python'} 05:06:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 05:06:04 INFO - mozversion application_buildid: 20161024021422 05:06:04 INFO - mozversion application_changeset: 4e2043b472d20feba39a349b220742f463983a67 05:06:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:06:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:04 INFO - mozversion application_name: Firefox 05:06:04 INFO - mozversion application_remotingname: firefox-dev 05:06:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:04 INFO - mozversion application_vendor: Mozilla 05:06:04 INFO - mozversion application_version: 51.0a2 05:06:04 INFO - mozversion platform_buildid: 20161024021422 05:06:04 INFO - mozversion platform_changeset: 4e2043b472d20feba39a349b220742f463983a67 05:06:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:04 INFO - mozversion platform_version: 51.0a2 05:06:04 INFO - using testdate: 1477310764 05:06:04 INFO - actual date: 1477310764 05:06:04 INFO - starting webserver on 'localhost:55943' 05:06:04 INFO - SUITE-START | Running 2 tests 05:06:04 INFO - TEST-START | dromaeo_css 05:06:04 INFO - operating with platform_type : linux_ 05:06:04 INFO - Initialising browser for dromaeo_css test... 05:06:04 INFO - TEST-INFO | started process 12889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoM22qp/profile http://localhost:55943/getInfo.html) 05:06:08 INFO - PROCESS | 12889 | __metrics Screen width/height:1600/1200 05:06:08 INFO - PROCESS | 12889 | colorDepth:24 05:06:08 INFO - PROCESS | 12889 | Browser inner width/height: 1024/697 05:06:08 INFO - PROCESS | 12889 | __metrics 05:06:09 INFO - PROCESS | 12889 | 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" 05:06:09 INFO - PROCESS | 12889 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:09 INFO - PROCESS | 12889 | console.error: PushService: 05:06:09 INFO - PROCESS | 12889 | stateChangeProcessEnqueue: Error transitioning state 05:06:09 INFO - PROCESS | 12889 | UnknownError 05:06:10 INFO - TEST-INFO | 12889: exit 0 05:06:10 INFO - Browser initialized. 05:06:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:10 INFO - TEST-INFO | started process 12982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoM22qp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:21 INFO - PROCESS | 12982 | RSS: Main: 144076800 05:06:21 INFO - PROCESS | 12982 | 05:07:12 INFO - PROCESS | 12982 | 1477310832970 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] 05:09:22 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55943/tests/dromaeo/cssquery-ext.html) 05:09:23 INFO - PROCESS | 12982 | RSS: Main: 246784000 05:09:23 INFO - PROCESS | 12982 | 05:12:25 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-ext.html (next: http://localhost:55943/tests/dromaeo/cssquery-jquery.html) 05:12:26 INFO - PROCESS | 12982 | RSS: Main: 231079936 05:12:26 INFO - PROCESS | 12982 | 05:15:28 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55943/tests/dromaeo/cssquery-mootools.html) 05:15:28 INFO - PROCESS | 12982 | RSS: Main: 173187072 05:15:28 INFO - PROCESS | 12982 | 05:18:30 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55943/tests/dromaeo/cssquery-prototype.html) 05:18:31 INFO - PROCESS | 12982 | RSS: Main: 217321472 05:18:31 INFO - PROCESS | 12982 | 05:21:33 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55943/tests/dromaeo/cssquery-yui.html) 05:21:34 INFO - PROCESS | 12982 | RSS: Main: 176148480 05:21:34 INFO - PROCESS | 12982 | 05:24:36 INFO - PROCESS | 12982 | Cycle 1(1): loaded http://localhost:55943/tests/dromaeo/cssquery-yui.html (next: http://localhost:55943/tests/dromaeo/cssquery-dojo.html) 05:24:37 INFO - PROCESS | 12982 | RSS: Main: 262410240 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | __start_tp_report 05:24:37 INFO - PROCESS | 12982 | _x_x_mozilla_page_load 05:24:37 INFO - PROCESS | 12982 | _x_x_mozilla_page_load_details 05:24:37 INFO - PROCESS | 12982 | |i|pagename|runs| 05:24:37 INFO - PROCESS | 12982 | |0;dojo.html;3082.7996180248288;3127.07751213392;3140.874365025399;3159.2575744700007;3181.2046988252937;5445.3737820150645;5531.502164805167;5541.944580554198;5562.554995600352;5572.303462067237;5544.473275038875;5602.271704678396;5612.69130197839;5617.606767526275;5620.690862002588;5060.797568097271;5085.720285384303;5126.102931986888;5160.329157209558;5162.561182299509;4971.651984361096;5113.105206588851;5159.432462429129;5167.354080739908;5168.870778230536;4959.206526955688;5004.549590536851;5006.97496512517;5015.774290156952;5049.192129259319;5037.899242015157;5043.697378157322;5116.872078198037;5119.846404607868;5145.742712864342;4875.609951203895;4994.775235114428;5032.597392208651;5056.696598204119;5091.210862316347;5316.840494785162;5333.466653334674;5345.251664766935;5412.07994640912;5416.025115479212;5722.084466485384;5731.856703582402;5829.650220986712;5839.883202335929;5845.503132233765;5821.796237131723;5854.356020837705;5865.384134665841;5866.384029676865;5877.118432235156;5451.236826844245;5484.451554844563;5487.122060470306;5512.3660779010925;5518.034343989786;6027.758889644453;6037.818865434044;6039.939600604026;6046.7278972445465;6062.878742425127;5637.8590535236945;5798.6810725410505;5800.564957628195;5811.3026436827;5812.389699081536;5753.252077229933;5808.912866307009;5849.268841394825;5851.151583020438;5921.111833225051;5856;5864.70676466175;5868.354481007086;5869.618474799125;5874.794382196603;5831.854203644943;5891.852703682443;5893.410658934158;5898.2332296802;5923.437273459014;4439.822407103744;4477.664175186874;4490.3040028795585;4527.501974782771;4529.207388706963;4058.127502586899;4058.5941405859176;4062.614051665087;4064.7154699170774;4085.7344272622777;5632.887342253214;5640.32316122068;5645.237892884408;5649.915251271152;5680.318361796611;5814.912776308359;5815.912761308584;5818;5863.589548731547;5950.8809823803285;5583.3858275589655;5586.636868603528;5588.2455868458055;5612.579056570774;5613.663180209201;5110.540051395318;5119.257707632372;5121.1550094234035;5126.026055049529;5154.407243167084;5909.852253693606;6018.488428483541;6095.207623008981;6106.992346262817;6118.4668989547035;5991.580589358703;6045.304789949212;6047.0627052806885;6079.604825686316;6085.6348619083;6179.28938172116;6237.906431403442;6262.5929314594405;6274.466670333073;6279.811605651837;5688.658680479184;5704.486596206361;5905.055191169392;5909.52723782105;5939.198208241832;5830.329512105993;5856.677882716492;5893.145493903446;5936.732847021808;5978.073398623221;5767.3367562729145;5813.04084825999;5813.970930145322;5826.329972053099;5830.125481177688;5643.604947653625;5783;5784.334801497882;5791.247137872049;5799.913001305068;5572.414896435978;5680.715964201856;5705.343885453226;5748.022836117786;5800.01399762033;5781.248437703071;5822.70886455684;5823.7379317929945;5829.271341082364;5848.853778655568;5554.777808887599;5597.104463285855;5713.171590119493;5717.1995920570325;5720.3707592165665 05:24:37 INFO - PROCESS | 12982 | |1;ext.html;591.52973386158;604.4922265297149;617.7004152985801;636.8471566823957;638.7891995641437;34334.31331373371;35128.121796955056;46610.53389466109;47187.764061179645;47344.76327618366;45413.31880021794;49068.26397604039;49078.50921490784;49133.754331228294;49276.50723492764;9374.531273436352;9515.381500202499;9541.904580954171;9546.952265238664;9556.569954352037;8430.283425908774;8634.438761480484;10981.17641176911;11219;11239.66281011571;11425;11524.905134012255;11533.538658453652;11534.769304613903;11538.711532211679;11267.154963377738;11329.773404531863;11342.603008894732;11351.091912647009;11352.602658906899;10763.623273185402;10842.72893177669;10866.61966831165;10869.67390978272;10870.402127883042;45167;45420.772896135306;45459.54540454586;45488.54511454876;45518;3677.595464498903;3730.421784623388;3739.9252014959548;3946.3488524393442;4244.299690551055;34116.48825267598;34138.82930585331;34255.657443425494;34426;36152;4564.475085365192;4591.632669386475;4652.813887444532;4662.6269898408045;4736.100140973204;350609;352978;353218;353587;354733;38817.417738733944;64393.67803160954;64584.677076614316;64774.352256477774;64873;39632.20735585272;41724.1655166895;41784;41814.58185418168;41830.58169418267;16722;16847.326106955585;16857.325706971827;16858.662826743395;16868.32526698943;12883.677908052374;13082.411291491906;13938.163710177421;14011.929940350234;14018.859811401959;9453.101955314234;9570.95214523923;9629.903700963041;9630.711078667651;9637.662681806243;3577.5528058992627;3621.9275614487565;3628.3106209820576;3648.6533779290926;3653.488511608377;1051.5382971639385;1077.8113830079706;1114.4539175803798;1143.4682872464343;1158.3860553906443;33294.33411331808;33463.66536334654;34548.581599288314;34580.30839383198;34603.826980864935;1144.1361771862191;1208.4078801387432;1215.1493954232074;1216.1426194532833;1238.789405801016;18086.366977155736;18119.909400452914;18237.45287641373;18412;18423.90788046078;523807;529890;530335;530930;531451;62691.373086269465;63316;63558.6822065896;63560.68219658872;63685.36314636887;1301.1217428235902;1348.8111664366998;1400.0549629000384;1400.0549629000384;1401.978970315446;33558;35443.11392215112;35496.46755298621;35626.28747424985;35654.643453565135;54216.72891635517;54879.17681234705;54895;54915.45084549263;54957.725211375284;424282;425715;427022;816981;840383;47566.28650570175;47709.04581908273;48276.27585586289;48766.75616621894;48932.02135957332;51602.22596660978;51928.22107668313;52056.47943520516;52600;52688;43100.13799723925;43959.78020109879;44374.556254438336;44607.77696111629;45122;45656.31515527203;45891.54108459006;46183;46722;46811.063778723554;43460;44339.55660443354;44348.77825610851;44660.33009504925;44836;38156;39051.60948390594;39762.40356394714;39779.80110099431;40674.389884152355;29530.261743456587;30127.246818829706;30248.243793905327;30370.088897333117;30527 05:24:37 INFO - PROCESS | 12982 | |2;jquery.html;387.31350657049717;430.13485705347847;432.7273817494978;436.3171636389051;449.1847297155662;16554.58613534662;20801.687974680393;20990.89504552475;21175.68236476454;21513.56972860542;20234.595308093867;20832.062557184963;20854.582908341825;20856.165753369845;21117.472063198427;5585.4919171823685;5791.362950075489;6033.577649564532;6069.0592958091565;6079.05774604937;5706.572007099484;5921.703914804264;5999.070144127667;6008.579399442041;6015.308239552442;5819.185314056035;5829.8834023319505;5837.153612726173;5843.240378750777;5844.094165404369;5711.514521265697;5834.154047663086;5834.795782147626;5837;5966.164736936832;5894.705264736746;5904.380040095814;5904.793332233394;5905.379935106838;5912.201852749867;13971.860281397157;14127.78808317876;14659.48691795782;14668.26658667073;19264.422067338;2540.796736261095;2613.6602241708647;2671.9599206011926;2720.3335182880123;3005.3238021445;10565.73585660357;10629.893701062967;10880.891191088067;10893.128549716004;11141.442927853575;1923.086533896402;1984.4641946674317;2009.2465325502938;2016.0927582587894;2061.701053347256;63777;65797.3420265796;82899;85063.57468212681;88858.55570722169;11080.501377438035;11092;11292.209545331747;11462.426878656033;11654.650360489199;16883.577910552336;16951;16951;16965.491035268715;16996.915015424845;8632.95683521591;8913.108689131035;8960.1935825776;8991.55042247885;9095.681651142178;8728.301735861116;8734.126587341216;8873.911260887438;8942;8948.328875334377;4993.725345106054;5063.822766203165;5103.617228707862;5104.336436263262;5185.455527169668;2044.509317763726;2082.843786715972;2109.9472513187293;2133.200049981257;2136.2096024471043;999.2355847776457;1013.4729940431082;1027.173125633857;1048.9527971241312;1078.2398409121552;18132.546686332946;18839.529011774815;19105.80894191068;19921.003949802453;20014.69977950332;984.5274268351232;985.8964808695127;1002.734275417015;1027.5786927359895;1042.817506936283;10363.844542331872;10880.12958963281;11165.83251251232;11179.944100279608;11245;522594;529497;535109;536955;538998;74833.62583187158;76063.61968190124;99775;100298;103393;1011.7066050845319;1019.5106348952544;1045.0281238448315;1060.965558580378;1061.830107182847;18110.90944545269;18462.907685461487;18654.34709785151;18662.626747465245;19029.904850475657;43813;44253.336199956386;44310.11379772451;45580.31629525493;45906.081878361576;60777.69611151916;61239.69380153071;62473.68763156155;63104.68447657732;63133.36866631275;8295.419320647738;8298.294644955246;8518.872216916876;8541.615627296911;8555.743327700178;10699.89300106989;12266.938665306616;12474.75050499003;12558.434870431036;12841.35793210317;10451.738706532398;11028.669139925814;11037.668869933916;11363.090952723927;11751.706207344885;10931.890681093088;11020.944895275472;11127.387993660102;11430.714232144262;11437.485313160762;11272.83090753623;11587.652370428901;11603.709907252049;11657.475413606238;11957.402129893297;8883.73348799537;8973;9113.863292050492;9334.113259240361;9335.579898904702;11192.832107518556;11321.830172547254;11330.263532870336;11386.43067846621;11507.42462876836 05:24:37 INFO - PROCESS | 12982 | |3;mootools.html;1526.6488707597252;1528.7401141805892;1704.8977061376318;1792.7131658934572;1813.7279408088793;8624.870626940601;8897.73306800797;8947.776305592355;9646.469444180568;9674.99999999999;9150.90849091509;9488.098630630078;9510.572024258907;9783.168430683401;9797.020297970217;2443.682321298233;2462.741412151725;2476.1457297232428;2480.8263421560496;2483.0316176691103;2157.6763485477145;2231.475603233239;2365.656979737937;2380.5119950410212;2480.379905023744;2442.5481285962064;2475.344033831036;2483.1681386735377;2483.4908843687;2494.4013436775135;2348.9765102349015;2426.3812727754485;2437.2688193541867;2440.3289095498703;2447.070113356931;2396.628522579003;2403.3510952043025;2410.6504556839336;2437.8415402998926;2442.4870777136643;9601.279904007228;9716.174125199366;9725.56234969428;9817.509124543743;9821.312508124434;5457.072297709399;5594.944050559483;5602.60781745278;5608.663480191201;5612.186232996233;3542.822858857073;3654.8903532894055;3695.796731179784;3702.2780557791243;3705.4627079073653;1261.7918043522807;1305.693162106904;1317.2294207888397;1340.8793208611173;1343.033016228314;13909.165450073027;13948.16311021342;13958.511452099128;13959.092658977133;13978.510752123728;4756.596803942776;4790.233562629963;4802.159622066124;4912.066707325596;4953.8018479261145;11436;11455.427228638535;11472.426378681033;11475.254108482923;11477.483513241925;5609.607327487037;5621.578381621313;5623.437656234344;5623.578231632644;5625.353084395347;5060.089183946925;5110.770015349318;5156.303898973591;5171.4052883918075;5181.922271165936;4385.144896745104;4416.205083085076;4428.512863585003;4486.304622783474;4508.887277818015;1268.3848333558217;1322.6759443936296;1324.973500529984;1329.4881470633682;1384.1279993603964;756.8978187944557;763.152900280688;795.8806179073069;806.4919100966354;813.0609146435876;6034.6982650867285;6050.6672133032225;6199;6241.78153764616;6303.086052522359;910.913463220993;976.3604838830578;992.8163289791412;1002.333448256901;1014.6600888702368;4175.373693945932;4245.766482843474;4274.893127671833;4369.847055353048;4389.100234451929;12019.519219231133;12431.62705118848;12447.93776031132;12448.25310481374;12452.31512266834;12254.387280635932;12350.75298494025;12354.938225308815;12369.93815030937;12391.318477483645;8537.445066070686;8853.070427605138;8855.203031727047;8869.600867960957;9064.50145242018;6016.458518733335;6197.5351848610635;6314.715837787218;6403.96798016007;6464.676766161767;10797.784044319227;11082.501287442246;11140.665780026613;11236.775264494829;11366.886331136582;10827.404492752974;11286.379249141377;11364.886351136383;11521.020713239468;11614.535418583162;8738.606762695821;8864.955675221583;8884.911150888409;8956.820863582561;8965.103489650983;8639.568021598769;9026;9039.954800225956;9072.138146876036;9106.225970792291;7714.537127772352;7730.420218483749;7756.88364674519;8174.7956301092945;8337.749867503984;7314.451416143704;7405.5926924019695;7434.962825185839;7569.8107547309555;7707.383409327353;6892.655367231518;6963.825904352431;7014.193367762773;7018.192907815463;7090.149182098181;4973.651844371004;5003.224500202475;5022.171341728574;5080.618953578571;5171.482851714798;5138.306328645585;5152.330197074356;5159.664621799571;5170.741462926914;5320 05:24:37 INFO - PROCESS | 12982 | |4;prototype.html;337.5460006291537;340.4008944258106;348.39910610743624;349.30662634670176;354.47891599348947;5377.516023557879;5579.386267510581;5609.270794796676;5609.411011843754;5615.129654903491;5377;5428.45715428457;5450.972745136279;5452.727363631812;5455.618106732531;4323.1353729254115;4323.41633879427;4330.047389574289;4331.7184383015165;4383.452068491439;4128.318827393477;4302.311630139178;4342.109867477159;4371.388005679208;4387.3857659927635;4273.188094262096;4285.957140428587;4292.377605247238;4301.118270754488;4318.935215971763;4264.573542645741;4277.1873344064525;4284.507281662618;4308.9353659695125;4311.784410779448;3780.8865734028022;3847.9230415391817;3863.8261278242267;3872.7482713623526;3873.341531939577;5261.710605916673;5290.127129023706;5323.083689871643;5338.759755810997;5367.704776237305;4895.388076490439;4895.804167833282;4901.460839307673;4911.803527858881;4918.901621967577;5451.618386712932;5605.887882242332;5684.8863022739315;5691.857703557403;5708.229389032511;4078.3490731024776;4080.326746086892;4085.1829634073006;4088.713790034699;4134.462519872427;5922.526197904157;5928.466438020599;5950.851228719273;5959.582829201958;6000.489958353545;4148.357004664282;4233.872983810491;4240.936385954213;4242.151569686013;4322.978385108055;4281.250781113293;4284.28628548583;4302.526722060571;4337.284348082531;4347.891302717394;4932.457429682732;4939.975300123477;4941.962187940501;4943.134951383494;4957.7769000395065;5559.77760889568;5706.629069110494;5708.286464191976;5725.6850873202375;5767.423257674199;1423.6830931388424;1461.3862177885314;1466.127654045841;1488.6948175624188;1495.3271028037427;1008.8436292374694;1026.8921763214755;1034.1778286262288;1043.3166276089173;1047.0838016735356;5464.1257398816;5499.752511137009;5532.917006244909;5546.306711661043;5555.250041244381;5769.8557536061935;5798.1302804579655;5798.855028624318;5837.795677151279;5843.415658434207;5329.307190065267;5533.889322213614;5566.55467562594;5581.413951535031;5601.439856014447;3119.610048743907;3161.9051428457183;3174.2699179188453;3174.349258402022;3176.682331766851;5874.618149820333;6113.052476866093;6144.293406258337;6163.445289923929;6171.444569988723;6026.005709057957;6026.186464827279;6064.907606875621;6082.148499210114;6083.330833608299;6147.44672979434;6148.508119350441;6154.846128846814;6181.84545386369;6223.159873417031;5662.292213473316;5903.291605007426;5923.54873056104;5943.286805583358;5969.343372229138;5814.447627475383;5829.446202610745;5858.648481091148;5867.559933004956;5948.762049518144;5911.7635294587735;6074.848128796815;6209.895052473763;6211.65835879031;6276.246850377984;4227.51383590891;4253.936190957076;4286.421333119989;4321.956780432155;4323.956760432356;5718.313802343745;5904.734286957011;5947.791827286024;5987.682709803837;5993.31076926159;5599.440055994368;5752.28096487939;5763.337216220189;5770.913436298543;5779.826605201851;5759.0838230004;5765.798197063083;5768.625039372428;5773.595848290579;5776.711164441677;5576.191452239322;5678.290213723285;5685.744141513559;5707.286589176353;5719.914201287067;5653.717314134195;5672.347680016851;5689.004424225826;5764.337101233412;5794.275715535558;5454.727263636882;5598.244237027949;5603.35561410443;5610.577547695821;5632.85917852057 05:24:37 INFO - PROCESS | 12982 | |5;yui.html;760.9954859585349;790.6560646118942;825.9297959673428;840.3823189955384;908.1100521488938;15110.244487775608;16374.672506549892;16376.181190940464;16504.587385315357;16566.999999999985;13781.58655240342;13974.790378144311;13976.231307278096;13990.230537320469;13997.580072597813;704.2007321689894;714.4962801225124;785.952842829432;842.4271495383138;855.1832999485507;860.8364410761967;861.1044513705739;863.7754183912165;866.138192498465;867.8177582707639;908.513945039404;909.077286554148;909.3225546967485;909.445238404576;910.7632015675906;752.8945947567345;770.5261264322429;880.9647614095461;890.247740659142;902.1835239108617;664.7108507799123;667.9098321726553;670.2359310386164;670.3899451499161;670.5842377725793;13884.4446222151;13961.232132232723;13980.790288145687;14010.299485025707;14021.579352619437;13343.532976345781;13826.377812998438;14084;14252.501162459368;14337.28313584327;12782.87217127826;12791.74416511674;13083.607491775261;13271.3364331784;13329.333533323392;4355.324924636687;4379.408779814716;4388;4490.842820501267;4528.977355113237;83076.58461707713;83425;83966.58016709938;84508.57745711293;85456;11024.669259922215;11039.889601104047;11092.445377731081;11134.16493762971;11515.136364772676;19997.900010499856;20082.297119601037;20112.49718757043;20123.396298110787;20132.29536966199;8509.27671147947;8635.611397487128;8646.308295336357;8713.564321783886;8890.555472226491;4862.149123903303;4999.750012499433;5015.222640490657;5069.543741063322;5207.869803254873;3380.323935212967;3494.825258737053;3496.3356962177104;3501.964980350215;3506.9123271918406;522.8536009917229;618.8236352639476;621.9129321894939;631.3717850738453;652.1912828093131;573.1202604002835;597.1550256387251;635.4535099814157;652.5236577298599;654.6890227142164;9182.908170918205;9455.52722363879;9679.758006049906;9859.211263098934;9995.600175992879;583.8248525442435;604.4620287943734;612.7212118486078;625.7559551774812;636.1761518833122;4528.732212801838;4579.564941330568;4719.952800471951;4728.40894888139;4832.573432269441;75318;88871;120785;124197;124398;104194.47902760438;104368.47815760873;104504;105514;105822.47088764507;89007.55496222607;89108.55445722729;91571.54214228886;96502.51748741351;96813.5159324213;8791.208791208823;8890.4665720057;9076.31927605419;9145.679901203426;9166.633334666538;54194.695010252675;55140.72429637826;55419.44580554143;55792.44207557872;56201.43798561962;80779.89437478571;82839.58580207061;83296;84159.15840841513;85650.57174714087;12394.566190183301;13206.405711743188;13207.273599952096;13657.453701852211;13899;13626.591202263948;14003.929980350033;14652;14657.267136643339;14993.025453345497;12771.10602257833;13420.731585368434;13743.381547830171;13832.654183645087;13961.581152565439;13221.637768686309;13251.469941202244;13478.52825151115;13811.102278351875;14009.299535023412;12492.937535312265;12803.61589152327;12841.67895802575;13058.281794501394;13283.734325313633;9379.577918993798;10220.897791021995;10237.539310730886;10526.263161578616;11489.425528723697;10837.403942783223;10889.727756806144;11043.558257669925;11058.502367393647;11324.716882078013 05:24:37 INFO - PROCESS | 12982 | __end_tp_report 05:24:37 INFO - PROCESS | 12982 | __start_cc_report 05:24:37 INFO - PROCESS | 12982 | _x_x_mozilla_cycle_collect,848 05:24:37 INFO - PROCESS | 12982 | __end_cc_report 05:24:37 INFO - PROCESS | 12982 | __startTimestamp1477311877094__endTimestamp 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | ------- Summary: start ------- 05:24:37 INFO - PROCESS | 12982 | Number of tests: 6 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#0] dojo.html Cycles:165 Average:5489.07 Median:5704.49 stddev:623.04 (10.9%) stddev-sans-first:595.67 05:24:37 INFO - PROCESS | 12982 | Values: 3082.8 3127.1 3140.9 3159.3 3181.2 5445.4 5531.5 5541.9 5562.6 5572.3 5544.5 5602.3 5612.7 5617.6 5620.7 5060.8 5085.7 5126.1 5160.3 5162.6 4971.7 5113.1 5159.4 5167.4 5168.9 4959.2 5004.5 5007.0 5015.8 5049.2 5037.9 5043.7 5116.9 5119.8 5145.7 4875.6 4994.8 5032.6 5056.7 5091.2 5316.8 5333.5 5345.3 5412.1 5416.0 5722.1 5731.9 5829.7 5839.9 5845.5 5821.8 5854.4 5865.4 5866.4 5877.1 5451.2 5484.5 5487.1 5512.4 5518.0 6027.8 6037.8 6039.9 6046.7 6062.9 5637.9 5798.7 5800.6 5811.3 5812.4 5753.3 5808.9 5849.3 5851.2 5921.1 5856.0 5864.7 5868.4 5869.6 5874.8 5831.9 5891.9 5893.4 5898.2 5923.4 4439.8 4477.7 4490.3 4527.5 4529.2 4058.1 4058.6 4062.6 4064.7 4085.7 5632.9 5640.3 5645.2 5649.9 5680.3 5814.9 5815.9 5818.0 5863.6 5950.9 5583.4 5586.6 5588.2 5612.6 5613.7 5110.5 5119.3 5121.2 5126.0 5154.4 5909.9 6018.5 6095.2 6107.0 6118.5 5991.6 6045.3 6047.1 6079.6 6085.6 6179.3 6237.9 6262.6 6274.5 6279.8 5688.7 5704.5 5905.1 5909.5 5939.2 5830.3 5856.7 5893.1 5936.7 5978.1 5767.3 5813.0 5814.0 5826.3 5830.1 5643.6 5783.0 5784.3 5791.2 5799.9 5572.4 5680.7 5705.3 5748.0 5800.0 5781.2 5822.7 5823.7 5829.3 5848.9 5554.8 5597.1 5713.2 5717.2 5720.4 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#1] ext.html Cycles:180 Average:65578.71 Median:41199.28 stddev:136875.10 (332.2%) stddev-sans-first:137172.11 05:24:37 INFO - PROCESS | 12982 | Values: 591.5 604.5 617.7 636.8 638.8 34334.3 35128.1 46610.5 47187.8 47344.8 45413.3 49068.3 49078.5 49133.8 49276.5 9374.5 9515.4 9541.9 9547.0 9556.6 8430.3 8634.4 10981.2 11219.0 11239.7 11425.0 11524.9 11533.5 11534.8 11538.7 11267.2 11329.8 11342.6 11351.1 11352.6 10763.6 10842.7 10866.6 10869.7 10870.4 45167.0 45420.8 45459.5 45488.5 45518.0 3677.6 3730.4 3739.9 3946.3 4244.3 34116.5 34138.8 34255.7 34426.0 36152.0 4564.5 4591.6 4652.8 4662.6 4736.1 350609.0 352978.0 353218.0 353587.0 354733.0 38817.4 64393.7 64584.7 64774.4 64873.0 39632.2 41724.2 41784.0 41814.6 41830.6 16722.0 16847.3 16857.3 16858.7 16868.3 12883.7 13082.4 13938.2 14011.9 14018.9 9453.1 9571.0 9629.9 9630.7 9637.7 3577.6 3621.9 3628.3 3648.7 3653.5 1051.5 1077.8 1114.5 1143.5 1158.4 33294.3 33463.7 34548.6 34580.3 34603.8 1144.1 1208.4 1215.1 1216.1 1238.8 18086.4 18119.9 18237.5 18412.0 18423.9 523807.0 529890.0 530335.0 530930.0 531451.0 62691.4 63316.0 63558.7 63560.7 63685.4 1301.1 1348.8 1400.1 1400.1 1402.0 33558.0 35443.1 35496.5 35626.3 35654.6 54216.7 54879.2 54895.0 54915.5 54957.7 424282.0 425715.0 427022.0 816981.0 840383.0 47566.3 47709.0 48276.3 48766.8 48932.0 51602.2 51928.2 52056.5 52600.0 52688.0 43100.1 43959.8 44374.6 44607.8 45122.0 45656.3 45891.5 46183.0 46722.0 46811.1 43460.0 44339.6 44348.8 44660.3 44836.0 38156.0 39051.6 39762.4 39779.8 40674.4 29530.3 30127.2 30248.2 30370.1 30527.0 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#2] jquery.html Cycles:180 Average:30263.25 Median:11654.44 stddev:87607.66 (751.7%) stddev-sans-first:87824.70 05:24:37 INFO - PROCESS | 12982 | Values: 387.3 430.1 432.7 436.3 449.2 16554.6 20801.7 20990.9 21175.7 21513.6 20234.6 20832.1 20854.6 20856.2 21117.5 5585.5 5791.4 6033.6 6069.1 6079.1 5706.6 5921.7 5999.1 6008.6 6015.3 5819.2 5829.9 5837.2 5843.2 5844.1 5711.5 5834.2 5834.8 5837.0 5966.2 5894.7 5904.4 5904.8 5905.4 5912.2 13971.9 14127.8 14659.5 14668.3 19264.4 2540.8 2613.7 2672.0 2720.3 3005.3 10565.7 10629.9 10880.9 10893.1 11141.4 1923.1 1984.5 2009.2 2016.1 2061.7 63777.0 65797.3 82899.0 85063.6 88858.6 11080.5 11092.0 11292.2 11462.4 11654.7 16883.6 16951.0 16951.0 16965.5 16996.9 8633.0 8913.1 8960.2 8991.6 9095.7 8728.3 8734.1 8873.9 8942.0 8948.3 4993.7 5063.8 5103.6 5104.3 5185.5 2044.5 2082.8 2109.9 2133.2 2136.2 999.2 1013.5 1027.2 1049.0 1078.2 18132.5 18839.5 19105.8 19921.0 20014.7 984.5 985.9 1002.7 1027.6 1042.8 10363.8 10880.1 11165.8 11179.9 11245.0 522594.0 529497.0 535109.0 536955.0 538998.0 74833.6 76063.6 99775.0 100298.0 103393.0 1011.7 1019.5 1045.0 1061.0 1061.8 18110.9 18462.9 18654.3 18662.6 19029.9 43813.0 44253.3 44310.1 45580.3 45906.1 60777.7 61239.7 62473.7 63104.7 63133.4 8295.4 8298.3 8518.9 8541.6 8555.7 10699.9 12266.9 12474.8 12558.4 12841.4 10451.7 11028.7 11037.7 11363.1 11751.7 10931.9 11020.9 11127.4 11430.7 11437.5 11272.8 11587.7 11603.7 11657.5 11957.4 8883.7 8973.0 9113.9 9334.1 9335.6 11192.8 11321.8 11330.3 11386.4 11507.4 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#3] mootools.html Cycles:180 Average:6237.98 Median:4932.93 stddev:3697.76 (75.0%) stddev-sans-first:3691.19 05:24:37 INFO - PROCESS | 12982 | Values: 1526.6 1528.7 1704.9 1792.7 1813.7 8624.9 8897.7 8947.8 9646.5 9675.0 9150.9 9488.1 9510.6 9783.2 9797.0 2443.7 2462.7 2476.1 2480.8 2483.0 2157.7 2231.5 2365.7 2380.5 2480.4 2442.5 2475.3 2483.2 2483.5 2494.4 2349.0 2426.4 2437.3 2440.3 2447.1 2396.6 2403.4 2410.7 2437.8 2442.5 9601.3 9716.2 9725.6 9817.5 9821.3 5457.1 5594.9 5602.6 5608.7 5612.2 3542.8 3654.9 3695.8 3702.3 3705.5 1261.8 1305.7 1317.2 1340.9 1343.0 13909.2 13948.2 13958.5 13959.1 13978.5 4756.6 4790.2 4802.2 4912.1 4953.8 11436.0 11455.4 11472.4 11475.3 11477.5 5609.6 5621.6 5623.4 5623.6 5625.4 5060.1 5110.8 5156.3 5171.4 5181.9 4385.1 4416.2 4428.5 4486.3 4508.9 1268.4 1322.7 1325.0 1329.5 1384.1 756.9 763.2 795.9 806.5 813.1 6034.7 6050.7 6199.0 6241.8 6303.1 910.9 976.4 992.8 1002.3 1014.7 4175.4 4245.8 4274.9 4369.8 4389.1 12019.5 12431.6 12447.9 12448.3 12452.3 12254.4 12350.8 12354.9 12369.9 12391.3 8537.4 8853.1 8855.2 8869.6 9064.5 6016.5 6197.5 6314.7 6404.0 6464.7 10797.8 11082.5 11140.7 11236.8 11366.9 10827.4 11286.4 11364.9 11521.0 11614.5 8738.6 8865.0 8884.9 8956.8 8965.1 8639.6 9026.0 9040.0 9072.1 9106.2 7714.5 7730.4 7756.9 8174.8 8337.7 7314.5 7405.6 7435.0 7569.8 7707.4 6892.7 6963.8 7014.2 7018.2 7090.1 4973.7 5003.2 5022.2 5080.6 5171.5 5138.3 5152.3 5159.7 5170.7 5320.0 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#4] prototype.html Cycles:180 Average:4857.53 Median:5516.33 stddev:1414.72 (25.6%) stddev-sans-first:1377.41 05:24:37 INFO - PROCESS | 12982 | Values: 337.5 340.4 348.4 349.3 354.5 5377.5 5579.4 5609.3 5609.4 5615.1 5377.0 5428.5 5451.0 5452.7 5455.6 4323.1 4323.4 4330.0 4331.7 4383.5 4128.3 4302.3 4342.1 4371.4 4387.4 4273.2 4286.0 4292.4 4301.1 4318.9 4264.6 4277.2 4284.5 4308.9 4311.8 3780.9 3847.9 3863.8 3872.7 3873.3 5261.7 5290.1 5323.1 5338.8 5367.7 4895.4 4895.8 4901.5 4911.8 4918.9 5451.6 5605.9 5684.9 5691.9 5708.2 4078.3 4080.3 4085.2 4088.7 4134.5 5922.5 5928.5 5950.9 5959.6 6000.5 4148.4 4233.9 4240.9 4242.2 4323.0 4281.3 4284.3 4302.5 4337.3 4347.9 4932.5 4940.0 4942.0 4943.1 4957.8 5559.8 5706.6 5708.3 5725.7 5767.4 1423.7 1461.4 1466.1 1488.7 1495.3 1008.8 1026.9 1034.2 1043.3 1047.1 5464.1 5499.8 5532.9 5546.3 5555.3 5769.9 5798.1 5798.9 5837.8 5843.4 5329.3 5533.9 5566.6 5581.4 5601.4 3119.6 3161.9 3174.3 3174.3 3176.7 5874.6 6113.1 6144.3 6163.4 6171.4 6026.0 6026.2 6064.9 6082.1 6083.3 6147.4 6148.5 6154.8 6181.8 6223.2 5662.3 5903.3 5923.5 5943.3 5969.3 5814.4 5829.4 5858.6 5867.6 5948.8 5911.8 6074.8 6209.9 6211.7 6276.2 4227.5 4253.9 4286.4 4322.0 4324.0 5718.3 5904.7 5947.8 5987.7 5993.3 5599.4 5752.3 5763.3 5770.9 5779.8 5759.1 5765.8 5768.6 5773.6 5776.7 5576.2 5678.3 5685.7 5707.3 5719.9 5653.7 5672.3 5689.0 5764.3 5794.3 5454.7 5598.2 5603.4 5610.6 5632.9 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - PROCESS | 12982 | [#5] yui.html Cycles:180 Average:21391.13 Median:4780.49 stddev:31284.01 (654.4%) stddev-sans-first:31333.42 05:24:37 INFO - PROCESS | 12982 | Values: 761.0 790.7 825.9 840.4 908.1 15110.2 16374.7 16376.2 16504.6 16567.0 13781.6 13974.8 13976.2 13990.2 13997.6 704.2 714.5 786.0 842.4 855.2 860.8 861.1 863.8 866.1 867.8 908.5 909.1 909.3 909.4 910.8 752.9 770.5 881.0 890.2 902.2 664.7 667.9 670.2 670.4 670.6 13884.4 13961.2 13980.8 14010.3 14021.6 13343.5 13826.4 14084.0 14252.5 14337.3 12782.9 12791.7 13083.6 13271.3 13329.3 4355.3 4379.4 4388.0 4490.8 4529.0 83076.6 83425.0 83966.6 84508.6 85456.0 11024.7 11039.9 11092.4 11134.2 11515.1 19997.9 20082.3 20112.5 20123.4 20132.3 8509.3 8635.6 8646.3 8713.6 8890.6 4862.1 4999.8 5015.2 5069.5 5207.9 3380.3 3494.8 3496.3 3502.0 3506.9 522.9 618.8 621.9 631.4 652.2 573.1 597.2 635.5 652.5 654.7 9182.9 9455.5 9679.8 9859.2 9995.6 583.8 604.5 612.7 625.8 636.2 4528.7 4579.6 4720.0 4728.4 4832.6 75318.0 88871.0 120785.0 124197.0 124398.0 104194.5 104368.5 104504.0 105514.0 105822.5 89007.6 89108.6 91571.5 96502.5 96813.5 8791.2 8890.5 9076.3 9145.7 9166.6 54194.7 55140.7 55419.4 55792.4 56201.4 80779.9 82839.6 83296.0 84159.2 85650.6 12394.6 13206.4 13207.3 13657.5 13899.0 13626.6 14003.9 14652.0 14657.3 14993.0 12771.1 13420.7 13743.4 13832.7 13961.6 13221.6 13251.5 13478.5 13811.1 14009.3 12492.9 12803.6 12841.7 13058.3 13283.7 9379.6 10220.9 10237.5 10526.3 11489.4 10837.4 10889.7 11043.6 11058.5 11324.7 05:24:37 INFO - PROCESS | 12982 | -------- Summary: end -------- 05:24:37 INFO - PROCESS | 12982 | 05:24:37 INFO - TEST-INFO | 12982: exit 0 05:24:37 INFO - TEST-OK | dromaeo_css | took 1113599ms 05:24:37 INFO - TEST-START | kraken 05:24:37 INFO - operating with platform_type : linux_ 05:24:37 INFO - Initialising browser for kraken test... 05:24:37 INFO - TEST-INFO | started process 13304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmBXpIU/profile http://localhost:55943/getInfo.html) 05:24:42 INFO - PROCESS | 13304 | __metrics Screen width/height:1600/1200 05:24:42 INFO - PROCESS | 13304 | colorDepth:24 05:24:42 INFO - PROCESS | 13304 | Browser inner width/height: 1024/697 05:24:42 INFO - PROCESS | 13304 | __metrics 05:24:42 INFO - PROCESS | 13304 | 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" 05:24:42 INFO - PROCESS | 13304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:43 INFO - PROCESS | 13304 | console.error: PushService: 05:24:43 INFO - PROCESS | 13304 | stateChangeProcessEnqueue: Error transitioning state 05:24:43 INFO - PROCESS | 13304 | UnknownError 05:24:43 INFO - TEST-INFO | 13304: exit 0 05:24:43 INFO - Browser initialized. 05:24:43 INFO - Running cycle 1/1 for kraken test... 05:24:43 INFO - TEST-INFO | started process 13395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmBXpIU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:55 INFO - PROCESS | 13395 | RSS: Main: 144932864 05:24:55 INFO - PROCESS | 13395 | 05:24:57 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55943/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:58 INFO - PROCESS | 13395 | RSS: Main: 186175488 05:24:58 INFO - PROCESS | 13395 | 05:25:01 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55943/tests/kraken/driver.html?testName=audio-dft) 05:25:01 INFO - PROCESS | 13395 | RSS: Main: 175681536 05:25:01 INFO - PROCESS | 13395 | 05:25:04 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55943/tests/kraken/driver.html?testName=audio-fft) 05:25:05 INFO - PROCESS | 13395 | RSS: Main: 267481088 05:25:05 INFO - PROCESS | 13395 | 05:25:07 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55943/tests/kraken/driver.html?testName=audio-oscillator) 05:25:07 INFO - PROCESS | 13395 | RSS: Main: 173035520 05:25:07 INFO - PROCESS | 13395 | 05:25:10 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55943/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:10 INFO - PROCESS | 13395 | RSS: Main: 174821376 05:25:10 INFO - PROCESS | 13395 | 05:25:13 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55943/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:14 INFO - PROCESS | 13395 | RSS: Main: 211226624 05:25:14 INFO - PROCESS | 13395 | 05:25:17 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55943/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:18 INFO - PROCESS | 13395 | RSS: Main: 210251776 05:25:18 INFO - PROCESS | 13395 | 05:25:20 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55943/tests/kraken/driver.html?testName=json-parse-financial) 05:25:21 INFO - PROCESS | 13395 | RSS: Main: 210001920 05:25:21 INFO - PROCESS | 13395 | 05:25:22 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55943/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:23 INFO - PROCESS | 13395 | RSS: Main: 171819008 05:25:23 INFO - PROCESS | 13395 | 05:25:25 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:25 INFO - PROCESS | 13395 | RSS: Main: 179335168 05:25:25 INFO - PROCESS | 13395 | 05:25:27 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:28 INFO - PROCESS | 13395 | RSS: Main: 188321792 05:25:28 INFO - PROCESS | 13395 | 05:25:30 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:31 INFO - PROCESS | 13395 | RSS: Main: 192585728 05:25:31 INFO - PROCESS | 13395 | 05:25:34 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:34 INFO - PROCESS | 13395 | RSS: Main: 193363968 05:25:34 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | Cycle 1(1): loaded http://localhost:55943/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55943/tests/kraken/driver.html?testName=ai-astar) 05:25:36 INFO - PROCESS | 13395 | RSS: Main: 244686848 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | __start_tp_report 05:25:36 INFO - PROCESS | 13395 | _x_x_mozilla_page_load 05:25:36 INFO - PROCESS | 13395 | _x_x_mozilla_page_load_details 05:25:36 INFO - PROCESS | 13395 | |i|pagename|runs| 05:25:36 INFO - PROCESS | 13395 | |0;ai-astar;108;110;109;108;109;110;110;109;111;110 05:25:36 INFO - PROCESS | 13395 | |1;audio-beat-detection;145;127;115;167;116;117;116;116;145;115 05:25:36 INFO - PROCESS | 13395 | |2;audio-dft;160;161;160;161;162;162;160;161;160;161 05:25:36 INFO - PROCESS | 13395 | |3;audio-fft;75;75;75;104;74;76;77;76;97;75 05:25:36 INFO - PROCESS | 13395 | |4;audio-oscillator;82;82;126;81;82;82;99;82;81;112 05:25:36 INFO - PROCESS | 13395 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;116;117 05:25:36 INFO - PROCESS | 13395 | |6;imaging-darkroom;145;146;146;145;146;146;146;145;145;145 05:25:36 INFO - PROCESS | 13395 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;95 05:25:36 INFO - PROCESS | 13395 | |8;json-parse-financial;49;49;49;49;49;50;52;49;49;49 05:25:36 INFO - PROCESS | 13395 | |9;json-stringify-tinderbox;59;59;59;59;59;59;59;59;59;58 05:25:36 INFO - PROCESS | 13395 | |10;stanford-crypto-aes;73;73;81;73;73;74;73;75;73;78 05:25:36 INFO - PROCESS | 13395 | |11;stanford-crypto-ccm;123;129;126;124;119;120;117;118;186;121 05:25:36 INFO - PROCESS | 13395 | |12;stanford-crypto-pbkdf2;152;153;158;156;148;151;152;153;149;148 05:25:36 INFO - PROCESS | 13395 | |13;stanford-crypto-sha256-iterative;65;61;62;62;66;62;62;62;60;61 05:25:36 INFO - PROCESS | 13395 | __end_tp_report 05:25:36 INFO - PROCESS | 13395 | __start_cc_report 05:25:36 INFO - PROCESS | 13395 | _x_x_mozilla_cycle_collect,1619 05:25:36 INFO - PROCESS | 13395 | __end_cc_report 05:25:36 INFO - PROCESS | 13395 | __startTimestamp1477311936711__endTimestamp 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | ------- Summary: start ------- 05:25:36 INFO - PROCESS | 13395 | Number of tests: 14 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:0.97 (0.9%) stddev-sans-first:0.88 05:25:36 INFO - PROCESS | 13395 | Values: 108.0 110.0 109.0 108.0 109.0 110.0 110.0 109.0 111.0 110.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:122.00 stddev:18.23 (14.9%) stddev-sans-first:18.26 05:25:36 INFO - PROCESS | 13395 | Values: 145.0 127.0 115.0 167.0 116.0 117.0 116.0 116.0 145.0 115.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:25:36 INFO - PROCESS | 13395 | Values: 160.0 161.0 160.0 161.0 162.0 162.0 160.0 161.0 160.0 161.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#3] audio-fft Cycles:10 Average:80.40 Median:75.50 stddev:10.75 (14.2%) stddev-sans-first:11.22 05:25:36 INFO - PROCESS | 13395 | Values: 75.0 75.0 75.0 104.0 74.0 76.0 77.0 76.0 97.0 75.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:82.00 stddev:16.11 (19.6%) stddev-sans-first:16.76 05:25:36 INFO - PROCESS | 13395 | Values: 82.0 82.0 126.0 81.0 82.0 82.0 99.0 82.0 81.0 112.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 05:25:36 INFO - PROCESS | 13395 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 05:25:36 INFO - PROCESS | 13395 | Values: 145.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:25:36 INFO - PROCESS | 13395 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.00 stddev:0.97 (2.0%) stddev-sans-first:1.01 05:25:36 INFO - PROCESS | 13395 | Values: 49.0 49.0 49.0 49.0 49.0 50.0 52.0 49.0 49.0 49.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 05:25:36 INFO - PROCESS | 13395 | Values: 59.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 58.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:73.50 stddev:2.76 (3.8%) stddev-sans-first:2.86 05:25:36 INFO - PROCESS | 13395 | Values: 73.0 73.0 81.0 73.0 73.0 74.0 73.0 75.0 73.0 78.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:123.50 stddev:20.61 (16.7%) stddev-sans-first:21.77 05:25:36 INFO - PROCESS | 13395 | Values: 123.0 129.0 126.0 124.0 119.0 120.0 117.0 118.0 186.0 121.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:152.50 stddev:3.27 (2.1%) stddev-sans-first:3.46 05:25:36 INFO - PROCESS | 13395 | Values: 152.0 153.0 158.0 156.0 148.0 151.0 152.0 153.0 149.0 148.0 05:25:36 INFO - PROCESS | 13395 | 05:25:36 INFO - PROCESS | 13395 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.83 (2.9%) stddev-sans-first:1.66 05:25:36 INFO - PROCESS | 13395 | Values: 65.0 61.0 62.0 62.0 66.0 62.0 62.0 62.0 60.0 61.0 05:25:36 INFO - PROCESS | 13395 | -------- Summary: end -------- 05:25:36 INFO - PROCESS | 13395 | 05:25:37 INFO - TEST-INFO | 13395: exit 0 05:25:37 INFO - TEST-OK | kraken | took 59888ms 05:25:37 INFO - SUITE-END | took 1173s 05:25:37 INFO - Completed test suite (00:19:33) 05:25:37 INFO - javascript benchmark 05:25:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.7996180248288, 3127.07751213392, 3140.874365025399, 3159.2575744700007, 3181.2046988252937, 5445.3737820150645, 5531.502164805167, 5541.944580554198, 5562.554995600352, 5572.303462067237, 5544.473275038875, 5602.271704678396, 5612.69130197839, 5617.606767526275, 5620.690862002588, 5060.797568097271, 5085.720285384303, 5126.102931986888, 5160.329157209558, 5162.561182299509, 4971.651984361096, 5113.105206588851, 5159.432462429129, 5167.354080739908, 5168.870778230536, 4959.206526955688, 5004.549590536851, 5006.97496512517, 5015.774290156952, 5049.192129259319, 5037.899242015157, 5043.697378157322, 5116.872078198037, 5119.846404607868, 5145.742712864342, 4875.609951203895, 4994.775235114428, 5032.597392208651, 5056.696598204119, 5091.210862316347, 5316.840494785162, 5333.466653334674, 5345.251664766935, 5412.07994640912, 5416.025115479212, 5722.084466485384, 5731.856703582402, 5829.650220986712, 5839.883202335929, 5845.503132233765, 5821.796237131723, 5854.356020837705, 5865.384134665841, 5866.384029676865, 5877.118432235156, 5451.236826844245, 5484.451554844563, 5487.122060470306, 5512.3660779010925, 5518.034343989786, 6027.758889644453, 6037.818865434044, 6039.939600604026, 6046.7278972445465, 6062.878742425127, 5637.8590535236945, 5798.6810725410505, 5800.564957628195, 5811.3026436827, 5812.389699081536, 5753.252077229933, 5808.912866307009, 5849.268841394825, 5851.151583020438, 5921.111833225051, 5856.0, 5864.70676466175, 5868.354481007086, 5869.618474799125, 5874.794382196603, 5831.854203644943, 5891.852703682443, 5893.410658934158, 5898.2332296802, 5923.437273459014, 4439.822407103744, 4477.664175186874, 4490.3040028795585, 4527.501974782771, 4529.207388706963, 4058.127502586899, 4058.5941405859176, 4062.614051665087, 4064.7154699170774, 4085.7344272622777, 5632.887342253214, 5640.32316122068, 5645.237892884408, 5649.915251271152, 5680.318361796611, 5814.912776308359, 5815.912761308584, 5818.0, 5863.589548731547, 5950.8809823803285, 5583.3858275589655, 5586.636868603528, 5588.2455868458055, 5612.579056570774, 5613.663180209201, 5110.540051395318, 5119.257707632372, 5121.1550094234035, 5126.026055049529, 5154.407243167084, 5909.852253693606, 6018.488428483541, 6095.207623008981, 6106.992346262817, 6118.4668989547035, 5991.580589358703, 6045.304789949212, 6047.0627052806885, 6079.604825686316, 6085.6348619083, 6179.28938172116, 6237.906431403442, 6262.5929314594405, 6274.466670333073, 6279.811605651837, 5688.658680479184, 5704.486596206361, 5905.055191169392, 5909.52723782105, 5939.198208241832, 5830.329512105993, 5856.677882716492, 5893.145493903446, 5936.732847021808, 5978.073398623221, 5767.3367562729145, 5813.04084825999, 5813.970930145322, 5826.329972053099, 5830.125481177688, 5643.604947653625, 5783.0, 5784.334801497882, 5791.247137872049, 5799.913001305068, 5572.414896435978, 5680.715964201856, 5705.343885453226, 5748.022836117786, 5800.01399762033, 5781.248437703071, 5822.70886455684, 5823.7379317929945, 5829.271341082364, 5848.853778655568, 5554.777808887599, 5597.104463285855, 5713.171590119493, 5717.1995920570325, 5720.3707592165665], "value": 5446.525208539805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.52973386158, 604.4922265297149, 617.7004152985801, 636.8471566823957, 638.7891995641437, 34334.31331373371, 35128.121796955056, 46610.53389466109, 47187.764061179645, 47344.76327618366, 45413.31880021794, 49068.26397604039, 49078.50921490784, 49133.754331228294, 49276.50723492764, 9374.531273436352, 9515.381500202499, 9541.904580954171, 9546.952265238664, 9556.569954352037, 8430.283425908774, 8634.438761480484, 10981.17641176911, 11219.0, 11239.66281011571, 11425.0, 11524.905134012255, 11533.538658453652, 11534.769304613903, 11538.711532211679, 11267.154963377738, 11329.773404531863, 11342.603008894732, 11351.091912647009, 11352.602658906899, 10763.623273185402, 10842.72893177669, 10866.61966831165, 10869.67390978272, 10870.402127883042, 45167.0, 45420.772896135306, 45459.54540454586, 45488.54511454876, 45518.0, 3677.595464498903, 3730.421784623388, 3739.9252014959548, 3946.3488524393442, 4244.299690551055, 34116.48825267598, 34138.82930585331, 34255.657443425494, 34426.0, 36152.0, 4564.475085365192, 4591.632669386475, 4652.813887444532, 4662.6269898408045, 4736.100140973204, 350609.0, 352978.0, 353218.0, 353587.0, 354733.0, 38817.417738733944, 64393.67803160954, 64584.677076614316, 64774.352256477774, 64873.0, 39632.20735585272, 41724.1655166895, 41784.0, 41814.58185418168, 41830.58169418267, 16722.0, 16847.326106955585, 16857.325706971827, 16858.662826743395, 16868.32526698943, 12883.677908052374, 13082.411291491906, 13938.163710177421, 14011.929940350234, 14018.859811401959, 9453.101955314234, 9570.95214523923, 9629.903700963041, 9630.711078667651, 9637.662681806243, 3577.5528058992627, 3621.9275614487565, 3628.3106209820576, 3648.6533779290926, 3653.488511608377, 1051.5382971639385, 1077.8113830079706, 1114.4539175803798, 1143.4682872464343, 1158.3860553906443, 33294.33411331808, 33463.66536334654, 34548.581599288314, 34580.30839383198, 34603.826980864935, 1144.1361771862191, 1208.4078801387432, 1215.1493954232074, 1216.1426194532833, 1238.789405801016, 18086.366977155736, 18119.909400452914, 18237.45287641373, 18412.0, 18423.90788046078, 523807.0, 529890.0, 530335.0, 530930.0, 531451.0, 62691.373086269465, 63316.0, 63558.6822065896, 63560.68219658872, 63685.36314636887, 1301.1217428235902, 1348.8111664366998, 1400.0549629000384, 1400.0549629000384, 1401.978970315446, 33558.0, 35443.11392215112, 35496.46755298621, 35626.28747424985, 35654.643453565135, 54216.72891635517, 54879.17681234705, 54895.0, 54915.45084549263, 54957.725211375284, 424282.0, 425715.0, 427022.0, 816981.0, 840383.0, 47566.28650570175, 47709.04581908273, 48276.27585586289, 48766.75616621894, 48932.02135957332, 51602.22596660978, 51928.22107668313, 52056.47943520516, 52600.0, 52688.0, 43100.13799723925, 43959.78020109879, 44374.556254438336, 44607.77696111629, 45122.0, 45656.31515527203, 45891.54108459006, 46183.0, 46722.0, 46811.063778723554, 43460.0, 44339.55660443354, 44348.77825610851, 44660.33009504925, 44836.0, 38156.0, 39051.60948390594, 39762.40356394714, 39779.80110099431, 40674.389884152355, 29530.261743456587, 30127.246818829706, 30248.243793905327, 30370.088897333117, 30527.0], "value": 20992.47076809001, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.31350657049717, 430.13485705347847, 432.7273817494978, 436.3171636389051, 449.1847297155662, 16554.58613534662, 20801.687974680393, 20990.89504552475, 21175.68236476454, 21513.56972860542, 20234.595308093867, 20832.062557184963, 20854.582908341825, 20856.165753369845, 21117.472063198427, 5585.4919171823685, 5791.362950075489, 6033.577649564532, 6069.0592958091565, 6079.05774604937, 5706.572007099484, 5921.703914804264, 5999.070144127667, 6008.579399442041, 6015.308239552442, 5819.185314056035, 5829.8834023319505, 5837.153612726173, 5843.240378750777, 5844.094165404369, 5711.514521265697, 5834.154047663086, 5834.795782147626, 5837.0, 5966.164736936832, 5894.705264736746, 5904.380040095814, 5904.793332233394, 5905.379935106838, 5912.201852749867, 13971.860281397157, 14127.78808317876, 14659.48691795782, 14668.26658667073, 19264.422067338, 2540.796736261095, 2613.6602241708647, 2671.9599206011926, 2720.3335182880123, 3005.3238021445, 10565.73585660357, 10629.893701062967, 10880.891191088067, 10893.128549716004, 11141.442927853575, 1923.086533896402, 1984.4641946674317, 2009.2465325502938, 2016.0927582587894, 2061.701053347256, 63777.0, 65797.3420265796, 82899.0, 85063.57468212681, 88858.55570722169, 11080.501377438035, 11092.0, 11292.209545331747, 11462.426878656033, 11654.650360489199, 16883.577910552336, 16951.0, 16951.0, 16965.491035268715, 16996.915015424845, 8632.95683521591, 8913.108689131035, 8960.1935825776, 8991.55042247885, 9095.681651142178, 8728.301735861116, 8734.126587341216, 8873.911260887438, 8942.0, 8948.328875334377, 4993.725345106054, 5063.822766203165, 5103.617228707862, 5104.336436263262, 5185.455527169668, 2044.509317763726, 2082.843786715972, 2109.9472513187293, 2133.200049981257, 2136.2096024471043, 999.2355847776457, 1013.4729940431082, 1027.173125633857, 1048.9527971241312, 1078.2398409121552, 18132.546686332946, 18839.529011774815, 19105.80894191068, 19921.003949802453, 20014.69977950332, 984.5274268351232, 985.8964808695127, 1002.734275417015, 1027.5786927359895, 1042.817506936283, 10363.844542331872, 10880.12958963281, 11165.83251251232, 11179.944100279608, 11245.0, 522594.0, 529497.0, 535109.0, 536955.0, 538998.0, 74833.62583187158, 76063.61968190124, 99775.0, 100298.0, 103393.0, 1011.7066050845319, 1019.5106348952544, 1045.0281238448315, 1060.965558580378, 1061.830107182847, 18110.90944545269, 18462.907685461487, 18654.34709785151, 18662.626747465245, 19029.904850475657, 43813.0, 44253.336199956386, 44310.11379772451, 45580.31629525493, 45906.081878361576, 60777.69611151916, 61239.69380153071, 62473.68763156155, 63104.68447657732, 63133.36866631275, 8295.419320647738, 8298.294644955246, 8518.872216916876, 8541.615627296911, 8555.743327700178, 10699.89300106989, 12266.938665306616, 12474.75050499003, 12558.434870431036, 12841.35793210317, 10451.738706532398, 11028.669139925814, 11037.668869933916, 11363.090952723927, 11751.706207344885, 10931.890681093088, 11020.944895275472, 11127.387993660102, 11430.714232144262, 11437.485313160762, 11272.83090753623, 11587.652370428901, 11603.709907252049, 11657.475413606238, 11957.402129893297, 8883.73348799537, 8973.0, 9113.863292050492, 9334.113259240361, 9335.579898904702, 11192.832107518556, 11321.830172547254, 11330.263532870336, 11386.43067846621, 11507.42462876836], "value": 9440.210916454384, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1526.6488707597252, 1528.7401141805892, 1704.8977061376318, 1792.7131658934572, 1813.7279408088793, 8624.870626940601, 8897.73306800797, 8947.776305592355, 9646.469444180568, 9674.99999999999, 9150.90849091509, 9488.098630630078, 9510.572024258907, 9783.168430683401, 9797.020297970217, 2443.682321298233, 2462.741412151725, 2476.1457297232428, 2480.8263421560496, 2483.0316176691103, 2157.6763485477145, 2231.475603233239, 2365.656979737937, 2380.5119950410212, 2480.379905023744, 2442.5481285962064, 2475.344033831036, 2483.1681386735377, 2483.4908843687, 2494.4013436775135, 2348.9765102349015, 2426.3812727754485, 2437.2688193541867, 2440.3289095498703, 2447.070113356931, 2396.628522579003, 2403.3510952043025, 2410.6504556839336, 2437.8415402998926, 2442.4870777136643, 9601.279904007228, 9716.174125199366, 9725.56234969428, 9817.509124543743, 9821.312508124434, 5457.072297709399, 5594.944050559483, 5602.60781745278, 5608.663480191201, 5612.186232996233, 3542.822858857073, 3654.8903532894055, 3695.796731179784, 3702.2780557791243, 3705.4627079073653, 1261.7918043522807, 1305.693162106904, 1317.2294207888397, 1340.8793208611173, 1343.033016228314, 13909.165450073027, 13948.16311021342, 13958.511452099128, 13959.092658977133, 13978.510752123728, 4756.596803942776, 4790.233562629963, 4802.159622066124, 4912.066707325596, 4953.8018479261145, 11436.0, 11455.427228638535, 11472.426378681033, 11475.254108482923, 11477.483513241925, 5609.607327487037, 5621.578381621313, 5623.437656234344, 5623.578231632644, 5625.353084395347, 5060.089183946925, 5110.770015349318, 5156.303898973591, 5171.4052883918075, 5181.922271165936, 4385.144896745104, 4416.205083085076, 4428.512863585003, 4486.304622783474, 4508.887277818015, 1268.3848333558217, 1322.6759443936296, 1324.973500529984, 1329.4881470633682, 1384.1279993603964, 756.8978187944557, 763.152900280688, 795.8806179073069, 806.4919100966354, 813.0609146435876, 6034.6982650867285, 6050.6672133032225, 6199.0, 6241.78153764616, 6303.086052522359, 910.913463220993, 976.3604838830578, 992.8163289791412, 1002.333448256901, 1014.6600888702368, 4175.373693945932, 4245.766482843474, 4274.893127671833, 4369.847055353048, 4389.100234451929, 12019.519219231133, 12431.62705118848, 12447.93776031132, 12448.25310481374, 12452.31512266834, 12254.387280635932, 12350.75298494025, 12354.938225308815, 12369.93815030937, 12391.318477483645, 8537.445066070686, 8853.070427605138, 8855.203031727047, 8869.600867960957, 9064.50145242018, 6016.458518733335, 6197.5351848610635, 6314.715837787218, 6403.96798016007, 6464.676766161767, 10797.784044319227, 11082.501287442246, 11140.665780026613, 11236.775264494829, 11366.886331136582, 10827.404492752974, 11286.379249141377, 11364.886351136383, 11521.020713239468, 11614.535418583162, 8738.606762695821, 8864.955675221583, 8884.911150888409, 8956.820863582561, 8965.103489650983, 8639.568021598769, 9026.0, 9039.954800225956, 9072.138146876036, 9106.225970792291, 7714.537127772352, 7730.420218483749, 7756.88364674519, 8174.7956301092945, 8337.749867503984, 7314.451416143704, 7405.5926924019695, 7434.962825185839, 7569.8107547309555, 7707.383409327353, 6892.655367231518, 6963.825904352431, 7014.193367762773, 7018.192907815463, 7090.149182098181, 4973.651844371004, 5003.224500202475, 5022.171341728574, 5080.618953578571, 5171.482851714798, 5138.306328645585, 5152.330197074356, 5159.664621799571, 5170.741462926914, 5320.0], "value": 4916.35738306558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.5460006291537, 340.4008944258106, 348.39910610743624, 349.30662634670176, 354.47891599348947, 5377.516023557879, 5579.386267510581, 5609.270794796676, 5609.411011843754, 5615.129654903491, 5377.0, 5428.45715428457, 5450.972745136279, 5452.727363631812, 5455.618106732531, 4323.1353729254115, 4323.41633879427, 4330.047389574289, 4331.7184383015165, 4383.452068491439, 4128.318827393477, 4302.311630139178, 4342.109867477159, 4371.388005679208, 4387.3857659927635, 4273.188094262096, 4285.957140428587, 4292.377605247238, 4301.118270754488, 4318.935215971763, 4264.573542645741, 4277.1873344064525, 4284.507281662618, 4308.9353659695125, 4311.784410779448, 3780.8865734028022, 3847.9230415391817, 3863.8261278242267, 3872.7482713623526, 3873.341531939577, 5261.710605916673, 5290.127129023706, 5323.083689871643, 5338.759755810997, 5367.704776237305, 4895.388076490439, 4895.804167833282, 4901.460839307673, 4911.803527858881, 4918.901621967577, 5451.618386712932, 5605.887882242332, 5684.8863022739315, 5691.857703557403, 5708.229389032511, 4078.3490731024776, 4080.326746086892, 4085.1829634073006, 4088.713790034699, 4134.462519872427, 5922.526197904157, 5928.466438020599, 5950.851228719273, 5959.582829201958, 6000.489958353545, 4148.357004664282, 4233.872983810491, 4240.936385954213, 4242.151569686013, 4322.978385108055, 4281.250781113293, 4284.28628548583, 4302.526722060571, 4337.284348082531, 4347.891302717394, 4932.457429682732, 4939.975300123477, 4941.962187940501, 4943.134951383494, 4957.7769000395065, 5559.77760889568, 5706.629069110494, 5708.286464191976, 5725.6850873202375, 5767.423257674199, 1423.6830931388424, 1461.3862177885314, 1466.127654045841, 1488.6948175624188, 1495.3271028037427, 1008.8436292374694, 1026.8921763214755, 1034.1778286262288, 1043.3166276089173, 1047.0838016735356, 5464.1257398816, 5499.752511137009, 5532.917006244909, 5546.306711661043, 5555.250041244381, 5769.8557536061935, 5798.1302804579655, 5798.855028624318, 5837.795677151279, 5843.415658434207, 5329.307190065267, 5533.889322213614, 5566.55467562594, 5581.413951535031, 5601.439856014447, 3119.610048743907, 3161.9051428457183, 3174.2699179188453, 3174.349258402022, 3176.682331766851, 5874.618149820333, 6113.052476866093, 6144.293406258337, 6163.445289923929, 6171.444569988723, 6026.005709057957, 6026.186464827279, 6064.907606875621, 6082.148499210114, 6083.330833608299, 6147.44672979434, 6148.508119350441, 6154.846128846814, 6181.84545386369, 6223.159873417031, 5662.292213473316, 5903.291605007426, 5923.54873056104, 5943.286805583358, 5969.343372229138, 5814.447627475383, 5829.446202610745, 5858.648481091148, 5867.559933004956, 5948.762049518144, 5911.7635294587735, 6074.848128796815, 6209.895052473763, 6211.65835879031, 6276.246850377984, 4227.51383590891, 4253.936190957076, 4286.421333119989, 4321.956780432155, 4323.956760432356, 5718.313802343745, 5904.734286957011, 5947.791827286024, 5987.682709803837, 5993.31076926159, 5599.440055994368, 5752.28096487939, 5763.337216220189, 5770.913436298543, 5779.826605201851, 5759.0838230004, 5765.798197063083, 5768.625039372428, 5773.595848290579, 5776.711164441677, 5576.191452239322, 5678.290213723285, 5685.744141513559, 5707.286589176353, 5719.914201287067, 5653.717314134195, 5672.347680016851, 5689.004424225826, 5764.337101233412, 5794.275715535558, 5454.727263636882, 5598.244237027949, 5603.35561410443, 5610.577547695821, 5632.85917852057], "value": 4409.544279785766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.9954859585349, 790.6560646118942, 825.9297959673428, 840.3823189955384, 908.1100521488938, 15110.244487775608, 16374.672506549892, 16376.181190940464, 16504.587385315357, 16566.999999999985, 13781.58655240342, 13974.790378144311, 13976.231307278096, 13990.230537320469, 13997.580072597813, 704.2007321689894, 714.4962801225124, 785.952842829432, 842.4271495383138, 855.1832999485507, 860.8364410761967, 861.1044513705739, 863.7754183912165, 866.138192498465, 867.8177582707639, 908.513945039404, 909.077286554148, 909.3225546967485, 909.445238404576, 910.7632015675906, 752.8945947567345, 770.5261264322429, 880.9647614095461, 890.247740659142, 902.1835239108617, 664.7108507799123, 667.9098321726553, 670.2359310386164, 670.3899451499161, 670.5842377725793, 13884.4446222151, 13961.232132232723, 13980.790288145687, 14010.299485025707, 14021.579352619437, 13343.532976345781, 13826.377812998438, 14084.0, 14252.501162459368, 14337.28313584327, 12782.87217127826, 12791.74416511674, 13083.607491775261, 13271.3364331784, 13329.333533323392, 4355.324924636687, 4379.408779814716, 4388.0, 4490.842820501267, 4528.977355113237, 83076.58461707713, 83425.0, 83966.58016709938, 84508.57745711293, 85456.0, 11024.669259922215, 11039.889601104047, 11092.445377731081, 11134.16493762971, 11515.136364772676, 19997.900010499856, 20082.297119601037, 20112.49718757043, 20123.396298110787, 20132.29536966199, 8509.27671147947, 8635.611397487128, 8646.308295336357, 8713.564321783886, 8890.555472226491, 4862.149123903303, 4999.750012499433, 5015.222640490657, 5069.543741063322, 5207.869803254873, 3380.323935212967, 3494.825258737053, 3496.3356962177104, 3501.964980350215, 3506.9123271918406, 522.8536009917229, 618.8236352639476, 621.9129321894939, 631.3717850738453, 652.1912828093131, 573.1202604002835, 597.1550256387251, 635.4535099814157, 652.5236577298599, 654.6890227142164, 9182.908170918205, 9455.52722363879, 9679.758006049906, 9859.211263098934, 9995.600175992879, 583.8248525442435, 604.4620287943734, 612.7212118486078, 625.7559551774812, 636.1761518833122, 4528.732212801838, 4579.564941330568, 4719.952800471951, 4728.40894888139, 4832.573432269441, 75318.0, 88871.0, 120785.0, 124197.0, 124398.0, 104194.47902760438, 104368.47815760873, 104504.0, 105514.0, 105822.47088764507, 89007.55496222607, 89108.55445722729, 91571.54214228886, 96502.51748741351, 96813.5159324213, 8791.208791208823, 8890.4665720057, 9076.31927605419, 9145.679901203426, 9166.633334666538, 54194.695010252675, 55140.72429637826, 55419.44580554143, 55792.44207557872, 56201.43798561962, 80779.89437478571, 82839.58580207061, 83296.0, 84159.15840841513, 85650.57174714087, 12394.566190183301, 13206.405711743188, 13207.273599952096, 13657.453701852211, 13899.0, 13626.591202263948, 14003.929980350033, 14652.0, 14657.267136643339, 14993.025453345497, 12771.10602257833, 13420.731585368434, 13743.381547830171, 13832.654183645087, 13961.581152565439, 13221.637768686309, 13251.469941202244, 13478.52825151115, 13811.102278351875, 14009.299535023412, 12492.937535312265, 12803.61589152327, 12841.67895802575, 13058.281794501394, 13283.734325313633, 9379.577918993798, 10220.897791021995, 10237.539310730886, 10526.263161578616, 11489.425528723697, 10837.403942783223, 10889.727756806144, 11043.558257669925, 11058.502367393647, 11324.716882078013], "value": 7589.903563199622, "unit": "score"}], "name": "dromaeo_css", "value": 7497.448927613872, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 109.0, 108.0, 109.0, 110.0, 110.0, 109.0, 111.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 127.0, 115.0, 167.0, 116.0, 117.0, 116.0, 116.0, 145.0, 115.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 162.0, 162.0, 160.0, 161.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 104.0, 74.0, 76.0, 77.0, 76.0, 97.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 126.0, 81.0, 82.0, 82.0, 99.0, 82.0, 81.0, 112.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 49.0, 50.0, 52.0, 49.0, 49.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 81.0, 73.0, 73.0, 74.0, 73.0, 75.0, 73.0, 78.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 126.0, 124.0, 119.0, 120.0, 117.0, 118.0, 186.0, 121.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 158.0, 156.0, 148.0, 151.0, 152.0, 153.0, 149.0, 148.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 62.0, 62.0, 66.0, 62.0, 62.0, 62.0, 60.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1452.2999999999997, "alertThreshold": 2.0}]} 05:25:37 INFO - Return code: 0 05:25:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:38 INFO - # TBPL SUCCESS # 05:25:38 INFO - Running post-action listener: _resource_record_post_action 05:25:38 INFO - [mozharness: 2016-10-24 12:25:38.042576Z] Finished run-tests step (success) 05:25:38 INFO - Running post-run listener: _resource_record_post_run 05:25:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327315456; Read time: 12; Write time: 269220 05:25:38 INFO - TinderboxPrint: CPU usage
12.9% 05:25:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:25:38 INFO - TinderboxPrint: I/O write bytes / time
327,315,456 / 269,220 05:25:38 INFO - TinderboxPrint: CPU idle
8,269.6 (87.1%) 05:25:38 INFO - TinderboxPrint: CPU user
1,183.4 (12.5%) 05:25:38 INFO - TinderboxPrint: Swap in / out
0 / 0 05:25:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 68812800; Read time: 0; Write time: 49708 05:25:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 247156736; Read time: 0; Write time: 216124 05:25:38 INFO - Running post-run listener: _upload_blobber_files 05:25:38 INFO - Blob upload gear active. 05:25:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:38 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 05:25:38 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:25:38 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:25:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:39 INFO - (blobuploader) - INFO - Done attempting. 05:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:43 INFO - (blobuploader) - INFO - Done attempting. 05:25:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:25:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:25:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:44 INFO - (blobuploader) - INFO - Done attempting. 05:25:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:44 INFO - Return code: 0 05:25:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33903fc29e35b39f485370ada25c283811e7ff1b697dbaf8001d994f4b54a47f962263dcd07345321d6d40cab45137abda1f93bac8575e00ee6343bcbd1e145a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4fb2836abfa9d579b96e51c66a42a44e58d552179d0598cb1a088d3b9dbc5590219bf19197c4ff21e94afa9a19ca5321fde298a9b3d2d53bde40135402702fda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b32a1dc0be39653beca661f892a4b2d8779da2791c81b221d4b0c245f958948bb3a2ee0b92e1280478bcc933c2e1c09bcb97e8c36a0ac4ff80362ca10b94ed5"} 05:25:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:44 INFO - Contents: 05:25:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33903fc29e35b39f485370ada25c283811e7ff1b697dbaf8001d994f4b54a47f962263dcd07345321d6d40cab45137abda1f93bac8575e00ee6343bcbd1e145a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4fb2836abfa9d579b96e51c66a42a44e58d552179d0598cb1a088d3b9dbc5590219bf19197c4ff21e94afa9a19ca5321fde298a9b3d2d53bde40135402702fda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b32a1dc0be39653beca661f892a4b2d8779da2791c81b221d4b0c245f958948bb3a2ee0b92e1280478bcc933c2e1c09bcb97e8c36a0ac4ff80362ca10b94ed5"} 05:25:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:44 INFO - Copying logs to upload dir... 05:25:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.629102 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-10-24 05:25:42.860554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 05:25:42.863775) ========= 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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 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-Aurora/sha512/33903fc29e35b39f485370ada25c283811e7ff1b697dbaf8001d994f4b54a47f962263dcd07345321d6d40cab45137abda1f93bac8575e00ee6343bcbd1e145a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4fb2836abfa9d579b96e51c66a42a44e58d552179d0598cb1a088d3b9dbc5590219bf19197c4ff21e94afa9a19ca5321fde298a9b3d2d53bde40135402702fda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b32a1dc0be39653beca661f892a4b2d8779da2791c81b221d4b0c245f958948bb3a2ee0b92e1280478bcc933c2e1c09bcb97e8c36a0ac4ff80362ca10b94ed5"} build_url:https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011561 build_url: 'https://queue.taskcluster.net/v1/task/MgQZPrHMRjS7cup-olaYzQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33903fc29e35b39f485370ada25c283811e7ff1b697dbaf8001d994f4b54a47f962263dcd07345321d6d40cab45137abda1f93bac8575e00ee6343bcbd1e145a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4fb2836abfa9d579b96e51c66a42a44e58d552179d0598cb1a088d3b9dbc5590219bf19197c4ff21e94afa9a19ca5321fde298a9b3d2d53bde40135402702fda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b32a1dc0be39653beca661f892a4b2d8779da2791c81b221d4b0c245f958948bb3a2ee0b92e1280478bcc933c2e1c09bcb97e8c36a0ac4ff80362ca10b94ed5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 05:25:42.889150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:25:42.889488) ========= 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=920470bf432669ad7e3a5387000001ca-1477308515.108431-1029907968 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 05:25:42.973408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 05:25:42.973743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 05:25:42.974056) ========= ========= Total master_lag: 0.51 =========