- {"dt": "2023-12-13T09:20:30.472141", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-13T09:20:30.473409", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-13T09:20:30.474411", "lvl": "info", "msg": "Start time: 2023-12-13 09:20:30.474354+00:00 (UTC)"}
- {"dt": "2023-12-13T09:20:30.475570", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-13T09:20:30.873198", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-13T09:20:31.088223", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-13T09:20:31.306830", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-13T09:20:31.541127", "lvl": "info", "msg": "validate duration: 1.07"}
- {"dt": "2023-12-13T09:20:31.541906", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-13T09:20:31.542909", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:20:31.543564", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-13T09:20:31.544085", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-13T09:20:31.544541", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-13T09:20:31.545443", "lvl": "debug", "msg": "Set_port 61981"}
- {"dt": "2023-12-13T09:20:31.546243", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:20:31.546908", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:20:31.548080", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:20:31.548725", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:20:31.549228", "lvl": "debug", "msg": "total size: 2061461 (1MB)"}
- {"dt": "2023-12-13T09:20:31.549676", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:20:31.857434", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-13T09:20:32.053261", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-13T09:20:32.149206", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-13T09:20:32.163127", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-13T09:20:32.246358", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-13T09:20:32.257723", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-13T09:20:32.264252", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-13T09:20:32.268565", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-13T09:20:32.344959", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-13T09:20:32.357248", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-13T09:20:32.361931", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-13T09:20:32.367782", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-13T09:20:32.372201", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-13T09:20:32.377739", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-13T09:20:32.443576", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-13T09:20:32.451019", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-13T09:20:32.458153", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-13T09:20:32.462549", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-13T09:20:32.468144", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-13T09:20:32.471708", "lvl": "info", "msg": "1MB downloaded in 0.92s (2.13MB/s)"}
- {"dt": "2023-12-13T09:20:32.472930", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:20:32.473608", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.93", "extra": {"label": "initrd", "md5sum": "a0ad17610175b7b908e36ce837aab631", "sha256sum": "608068551f290d9bb01f4a7f87bf2c534cf60059e97a08aa9e577144bbefef69", "sha512sum": "ebf601ed797b49a55749d6fb5d102b39417c36f6154e1aa8db64b0567c1881c1fc3cbcfcd35f31df290b222a272afdd70591ae6df1be1c4c5d50f593149de678", "size": ! "2061461"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:20:32.475054", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:20:32.475907", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-13T09:20:32.476709", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-13T09:20:32.477784", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-13T09:20:32.478453", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/kernel/Image"}
- {"dt": "2023-12-13T09:20:32.479004", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-13T09:20:32.479606", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:20:32.796934", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-13T09:20:33.420740", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-13T09:20:33.718080", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-13T09:20:34.015630", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-13T09:20:34.322998", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-13T09:20:34.715219", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-13T09:20:35.038535", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-13T09:20:35.414936", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-13T09:20:35.737887", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-13T09:20:36.050179", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-13T09:20:36.355788", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-13T09:20:36.657798", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-13T09:20:36.955006", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-13T09:20:37.252788", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-13T09:20:37.547560", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-13T09:20:37.829156", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-13T09:20:38.079579", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-13T09:20:38.372060", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-13T09:20:38.661865", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-13T09:20:38.936574", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-13T09:20:39.188388", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-13T09:20:39.189911", "lvl": "info", "msg": "37MB downloaded in 6.71s (5.57MB/s)"}
- {"dt": "2023-12-13T09:20:39.191046", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:07) [common]"}
- {"dt": "2023-12-13T09:20:39.191782", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "6.71", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:20:39.193264", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:07) [common]"}
- {"dt": "2023-12-13T09:20:39.194118", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-12-13T09:20:39.194913", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-12-13T09:20:39.196075", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:20:39.196768", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:20:39.197327", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-13T09:20:39.197877", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:20:39.513083", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-13T09:20:39.614398", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-13T09:20:39.620923", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-13T09:20:39.625352", "lvl": "info", "msg": "0MB downloaded in 0.43s (0.18MB/s)"}
- {"dt": "2023-12-13T09:20:39.627939", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:20:39.628653", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.43", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:20:39.630099", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:20:39.630888", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-12-13T09:20:39.631718", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-12-13T09:20:39.632806", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/10/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-13T09:20:39.633491", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:20:39.634054", "lvl": "debug", "msg": "total size: 454056084 (433MB)"}
- {"dt": "2023-12-13T09:20:39.634599", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-13T09:20:39.974108", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-13T09:20:46.022057", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-13T09:20:50.512411", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-13T09:20:55.362912", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-13T09:20:59.373277", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-13T09:21:04.095537", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-13T09:21:08.787069", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-13T09:21:14.444011", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-13T09:21:21.105454", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-13T09:21:27.181985", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-13T09:21:32.155630", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-13T09:21:37.422139", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-13T09:21:43.174188", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-13T09:21:48.210582", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-13T09:21:53.007547", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-13T09:21:57.517720", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-13T09:22:01.611759", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-13T09:22:06.052921", "lvl": "debug", "msg": "progress  85% (368MB)"}
- {"dt": "2023-12-13T09:22:10.446664", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-13T09:22:14.813146", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-13T09:22:22.208230", "lvl": "debug", "msg": "progress 100% (433MB)"}
- {"dt": "2023-12-13T09:22:26.107200", "lvl": "info", "msg": "433MB downloaded in 106.47s (4.07MB/s)"}
- {"dt": "2023-12-13T09:22:26.109809", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:46) [common]"}
- {"dt": "2023-12-13T09:22:26.110916", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "106.48", "extra": {"label": "nbdroot", "md5sum": "e46890b564bc01107f24603b66c479e4", "sha256sum": "6992f4825e063bb05607f523b617222b5237a60592694b5ebd85db56c35ff5cf", "sha512sum": "69e17e5662761afd7eb214091baed171c60277d1c10d262069db6927aa673d3c0722c615b8311b7e93017e5a6ef742e933eac3f98e45a3ad8ab376ea52325714", "size": ! "454056084"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:26.113435", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:46) [common]"}
- {"dt": "2023-12-13T09:22:26.114619", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.116916", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd"}
- {"dt": "2023-12-13T09:22:26.118722", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin"}
- {"dt": "2023-12-13T09:22:26.120130", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/tests"}
- {"dt": "2023-12-13T09:22:26.121158", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/results"}
- {"dt": "2023-12-13T09:22:26.122175", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-add-keys"}
- {"dt": "2023-12-13T09:22:26.123630", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-add-sources"}
- {"dt": "2023-12-13T09:22:26.124990", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-background-process-start"}
- {"dt": "2023-12-13T09:22:26.126347", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-background-process-stop"}
- {"dt": "2023-12-13T09:22:26.127732", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-common-functions"}
- {"dt": "2023-12-13T09:22:26.129077", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-echo-ipv4"}
- {"dt": "2023-12-13T09:22:26.130422", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-install-packages"}
- {"dt": "2023-12-13T09:22:26.131794", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-installed-packages"}
- {"dt": "2023-12-13T09:22:26.133126", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-os-build"}
- {"dt": "2023-12-13T09:22:26.134472", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-probe-channel"}
- {"dt": "2023-12-13T09:22:26.135902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-probe-ip"}
- {"dt": "2023-12-13T09:22:26.137266", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-target-ip"}
- {"dt": "2023-12-13T09:22:26.138602", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-target-mac"}
- {"dt": "2023-12-13T09:22:26.140035", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-target-storage"}
- {"dt": "2023-12-13T09:22:26.141404", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-case"}
- {"dt": "2023-12-13T09:22:26.142755", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-event"}
- {"dt": "2023-12-13T09:22:26.144162", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-feedback"}
- {"dt": "2023-12-13T09:22:26.145501", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-raise"}
- {"dt": "2023-12-13T09:22:26.146871", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-reference"}
- {"dt": "2023-12-13T09:22:26.148316", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-runner"}
- {"dt": "2023-12-13T09:22:26.149659", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-set"}
- {"dt": "2023-12-13T09:22:26.151015", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-test-shell"}
- {"dt": "2023-12-13T09:22:26.152442", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-13T09:22:26.153973", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-13T09:22:26.155411", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/environment"}
- {"dt": "2023-12-13T09:22:26.156563", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-13T09:22:26.157323", "lvl": "debug", "msg": "- LAVA_JOB_ID=34218"}
- {"dt": "2023-12-13T09:22:26.158007", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-13T09:22:26.159052", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.161434", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.162340", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.163016", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-13T09:22:26.163829", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.164607", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.165252", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-13T09:22:26.166013", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.166842", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.167659", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-13T09:22:26.168561", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.169299", "lvl": "debug", "msg": "Using /lava-34218 at stage 0"}
- {"dt": "2023-12-13T09:22:26.170261", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:26.171005", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/0/tests/0_network-veth'"}
- {"dt": "2023-12-13T09:22:26.796888", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-13T09:22:26.803716", "lvl": "debug", "msg": "uuid=34218_1.5.4.1 testdef=None"}
- {"dt": "2023-12-13T09:22:26.805537", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:26.806815", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.64", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:26.809457", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.814845", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.815593", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:26.817101", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.823067", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.823842", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.1", "uuid": "34218_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:26.825426", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.831271", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/0/tests/0_network-veth test_uuid 34218_1.5.4.1"}
- {"dt": "2023-12-13T09:22:26.832891", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:26.833626", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/0/tests/0_network-veth/run.sh", "from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:26.835274", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:28:05) [common]"}
- {"dt": "2023-12-13T09:22:26.836035", "lvl": "debug", "msg": "Using /lava-34218 at stage 1"}
- {"dt": "2023-12-13T09:22:26.837069", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:26.837825", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-13T09:22:27.463476", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/1_ci-busybox"}
- {"dt": "2023-12-13T09:22:27.476759", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-13T09:22:27.480030", "lvl": "debug", "msg": "uuid=34218_1.5.4.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-13T09:22:27.481118", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:27.481750", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:27.483041", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:28:04) [common]"}
- {"dt": "2023-12-13T09:22:27.488772", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:27.489436", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:27.490765", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:28:04) [common]"}
- {"dt": "2023-12-13T09:22:27.498743", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:27.499485", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.5", "uuid": "34218_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:27.500921", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:28:04) [common]"}
- {"dt": "2023-12-13T09:22:27.508257", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/1_ci-busybox test_uuid 34218_1.5.4.5"}
- {"dt": "2023-12-13T09:22:27.509805", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:27.510473", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/1_ci-busybox/run.sh", "from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:27.511950", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:28:04) [common]"}
- {"dt": "2023-12-13T09:22:27.512571", "lvl": "debug", "msg": "Using /lava-34218 at stage 1"}
- {"dt": "2023-12-13T09:22:27.513455", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:27.514111", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-13T09:22:28.148473", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-13T09:22:28.163215", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-13T09:22:28.166278", "lvl": "debug", "msg": "uuid=34218_1.5.4.9 testdef=None"}
- {"dt": "2023-12-13T09:22:28.167456", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:28.168197", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.169700", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.174863", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.175600", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.177105", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.184320", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.185034", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.9", "uuid": "34218_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.186602", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.193180", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/2_ci-smoke-tests-basic test_uuid 34218_1.5.4.9"}
- {"dt": "2023-12-13T09:22:28.195946", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.196677", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/2_ci-smoke-tests-basic/run.sh", "from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.198271", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.199000", "lvl": "debug", "msg": "Using /lava-34218 at stage 1"}
- {"dt": "2023-12-13T09:22:28.200075", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:28.200837", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-13T09:22:28.827068", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/3_ci-service-check"}
- {"dt": "2023-12-13T09:22:28.845616", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-13T09:22:28.849253", "lvl": "debug", "msg": "uuid=34218_1.5.4.13 testdef=None"}
- {"dt": "2023-12-13T09:22:28.850310", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:28.851037", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.852589", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.857831", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.858520", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.860525", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.867636", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.868356", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.13", "uuid": "34218_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.869879", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.876668", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/3_ci-service-check test_uuid 34218_1.5.4.13"}
- {"dt": "2023-12-13T09:22:28.877404", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-13T09:22:28.878012", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-13T09:22:28.879471", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:28.880188", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1/tests/3_ci-service-check/run.sh", "from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:28.881743", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:28:03) [common]"}
- {"dt": "2023-12-13T09:22:28.882466", "lvl": "debug", "msg": "Using /lava-34218 at stage 2"}
- {"dt": "2023-12-13T09:22:28.883533", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:28.884297", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/2/tests/4_screenshooter'"}
- {"dt": "2023-12-13T09:22:29.499578", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-13T09:22:29.507404", "lvl": "debug", "msg": "uuid=34218_1.5.4.17 testdef=None"}
- {"dt": "2023-12-13T09:22:29.508630", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:29.509384", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:29.510847", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:28:02) [common]"}
- {"dt": "2023-12-13T09:22:29.515458", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:29.516183", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:29.517697", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:28:02) [common]"}
- {"dt": "2023-12-13T09:22:29.523738", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:29.524463", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.17", "uuid": "34218_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:29.525986", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:28:02) [common]"}
- {"dt": "2023-12-13T09:22:29.531750", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/2/tests/4_screenshooter test_uuid 34218_1.5.4.17"}
- {"dt": "2023-12-13T09:22:29.533353", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:29.534069", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/2/tests/4_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:29.535733", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:28:02) [common]"}
- {"dt": "2023-12-13T09:22:29.536480", "lvl": "debug", "msg": "Using /lava-34218 at stage 3"}
- {"dt": "2023-12-13T09:22:29.537517", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:22:29.538281", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-13T09:22:30.194791", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-13T09:22:30.209585", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-13T09:22:30.213224", "lvl": "debug", "msg": "uuid=34218_1.5.4.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-13T09:22:30.214357", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:30.215092", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.68", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:30.216631", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.222082", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.222752", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:30.224296", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.232188", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.232903", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34218_1.5.4.21", "uuid": "34218_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:30.234446", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.241683", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3/tests/5_ci-dumpjournal test_uuid 34218_1.5.4.21"}
- {"dt": "2023-12-13T09:22:30.243323", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.244046", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3/tests/5_ci-dumpjournal/run.sh", "from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34218_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:30.245453", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-13T09:22:30.246077", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/0 for stage 0"}
- {"dt": "2023-12-13T09:22:30.247044", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-13T09:22:30.247955", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/1 for stage 1"}
- {"dt": "2023-12-13T09:22:30.248969", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-13T09:22:30.249639", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-13T09:22:30.250217", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-13T09:22:30.250938", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/2 for stage 2"}
- {"dt": "2023-12-13T09:22:30.251994", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-13T09:22:30.252818", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34218/lava-overlay-dk1xtjzd/lava-34218/3 for stage 3"}
- {"dt": "2023-12-13T09:22:30.253822", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-13T09:22:30.254850", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-13T09:22:30.255770", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.918686", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:22:30.919699", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.921008", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.921938", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:22:30.922780", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.923668", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.924519", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.925275", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.926062", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:28:01) [common]"}
- {"dt": "2023-12-13T09:22:30.926726", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-13T09:22:30.927470", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61981, NBD-ROOT: 34218/nbd-deploy-fhqsmkb3/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:22:30.930986", "lvl": "debug", "msg": "nbd-server 61981 /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:22:30.958758", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-13T09:22:30.962718", "lvl": "debug", "msg": "output: ** (process:8211): WARNING **: 09:22:30.956: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-13T09:22:30.964147", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-13T09:22:30.965456", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-13T09:22:30.968165", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.969126", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:59) [common]"}
- {"dt": "2023-12-13T09:22:30.969919", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:22:30.970683", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:22:30.971932", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.972757", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:22:30.973968", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-13T09:22:30.974545", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:22:30.975047", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-13T09:22:30.976430", "lvl": "debug", "msg": "- {DTB}: 34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:22:30.976949", "lvl": "debug", "msg": "- {INITRD}: 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:22:30.977416", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-13T09:22:30.977853", "lvl": "debug", "msg": "- {KERNEL}: 34218/nbd-deploy-fhqsmkb3/kernel/Image"}
- {"dt": "2023-12-13T09:22:30.978285", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-13T09:22:30.978715", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-13T09:22:30.980053", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61981"}
- {"dt": "2023-12-13T09:22:30.980630", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-13T09:22:30.981086", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-13T09:22:30.981518", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-13T09:22:30.981944", "lvl": "debug", "msg": "- {RAMDISK}: 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:22:30.982380", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-13T09:22:30.982824", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-13T09:22:30.983319", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-13T09:22:30.983762", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-13T09:22:30.984185", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-13T09:22:30.984608", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-13T09:22:30.985052", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-13T09:22:30.985478", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:30.985919", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:30.986370", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-13T09:22:30.986796", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:22:30.987272", "lvl": "info", "msg": "- tftp 0x68080000 34218/nbd-deploy-fhqsmkb3/kernel/Image"}
- {"dt": "2023-12-13T09:22:30.987723", "lvl": "info", "msg": "- tftp 0x4a000000 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:22:30.988175", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:22:30.988606", "lvl": "info", "msg": "- tftp 0x68000000 34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:22:30.989038", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61981 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:22:30.989532", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:22:30.990143", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:22:30.990695", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:30.992142", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:22:30.992748", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-13T09:22:34.117536", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-13T09:22:34.121185", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-13T09:22:34.122309", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:22:34.123339", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:22:34.124304", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:22:34.125635", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-13T09:22:37.539681", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:22:38.613118", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:22:38.648798", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-13T09:22:38.753094", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:22:38.755460", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.63", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:22:38.759588", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:22:38.762072", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-13T09:22:38.763857", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-13T09:22:38.764499", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-13T09:22:38.766197", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-13T09:22:38.766888", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-13T09:22:38.767493", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-13T09:22:38.818542", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initial\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:38.820168", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:38.877827", "lvl": "target", "msg": "NOTICE:  BL2: Board is \uFFFD\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:38.880068", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.001486", "lvl": "target", "msg": "NOTICE:  BL2: Board is\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program L\0\0\0\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.043812", "lvl": "target", "msg": "NOTICE:  BL2: PRR i\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.045853", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.091299", "lvl": "target", "msg": "NOTICE:  BL2: Board is Start\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.093267", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.140825", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.142791", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.144997", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:22:39.193670", "lvl": "target", "msg": "NOTICE:  BL2: Boot device \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.195856", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.197527", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:22:39.198644", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:22:39.253917", "lvl": "target", "msg": "NOTICE:  BL2: L\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.255331", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.256769", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:22:39.257976", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:22:39.259306", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-13T09:22:39.260803", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-13T09:22:39.322082", "lvl": "target", "msg": "NOTICE:  BL2: DD\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:22:39.323928", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:22:39.325440", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:22:39.327245", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:22:39.328788", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-13T09:22:39.330262", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-13T09:22:39.331806", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-13T09:22:39.333222", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-13T09:22:39.367585", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-13T09:22:39.370133", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-13T09:22:39.372096", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-13T09:22:39.373857", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-13T09:22:39.375708", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:39.377410", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:39.379028", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:39.379747", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:39.380182", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-13T09:22:39.380612", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-13T09:22:39.381168", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-13T09:22:39.410174", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-13T09:22:39.411400", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-13T09:22:39.412409", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-13T09:22:39.413281", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-13T09:22:39.414211", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-13T09:22:39.415199", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-13T09:22:39.416281", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-13T09:22:39.450381", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-13T09:22:39.452186", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-13T09:22:39.454166", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-13T09:22:39.781894", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:22:39.783804", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:22:39.785579", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-13T09:22:39.787426", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:22:39.789616", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-13T09:22:39.791265", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-13T09:22:39.975842", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-13T09:22:40.006381", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-13T09:22:40.008344", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-13T09:22:40.010740", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:40.012684", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:40.014431", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:22:40.016219", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:22:40.040602", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-13T09:22:40.220846", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-13T09:22:40.222740", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-13T09:22:40.224512", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-13T09:22:40.227033", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-13T09:22:40.271261", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-13T09:22:40.287879", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-13T09:22:40.340285", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-13T09:22:40.342778", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-13T09:22:40.345229", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-12-13T09:22:40.347336", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-13T09:22:40.349368", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-12-13T09:22:40.367032", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-13T09:22:40.369331", "lvl": "debug", "msg": "Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-12-13T09:22:40.370705", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-13T09:22:40.472963", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-13T09:22:40.475072", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-13T09:22:40.494841", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-13T09:22:40.496173", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-13T09:22:40.597909", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:40.600078", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-13T09:22:40.606648", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:40.609126", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-13T09:22:40.710523", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:40.712424", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-13T09:22:40.718384", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:22:40.720602", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-13T09:22:40.823109", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-13T09:22:40.825260", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-13T09:22:40.830329", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-13T09:22:43.870913", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-13T09:22:43.872908", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-13T09:22:44.126438", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-13T09:22:44.637267", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-13T09:22:44.908828", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1031 ms)"}
- {"dt": "2023-12-13T09:22:44.911441", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-13T09:22:45.013570", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:22:45.015861", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-13T09:22:45.020632", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:22:45.023397", "lvl": "input", "msg": "tftp 0x68080000 34218/nbd-deploy-fhqsmkb3/kernel/Image\n"}
- {"dt": "2023-12-13T09:22:45.126140", "lvl": "target", "msg": "=> tftp 0x68080000 34218/nbd-deploy-fhqsmkb3/kernel/Image"}
- {"dt": "2023-12-13T09:22:45.128461", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-13T09:22:45.165171", "lvl": "target", "msg": "tftp 0x68080000 34218/nbd-deploy-fhqsmkb3/kernel/Image"}
- {"dt": "2023-12-13T09:22:45.166134", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:22:45.166824", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:22:45.168739", "lvl": "target", "msg": "Filename '34218/nbd-deploy-fhqsmkb3/kernel/Image'."}
- {"dt": "2023-12-13T09:22:45.170058", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-13T09:22:45.757818", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-13T09:22:46.348868", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:46.907954", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:47.482766", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:48.057630", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:48.615942", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:49.175672", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:49.734540", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:50.309253", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:50.884210", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:51.443553", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:51.986503", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:52.545396", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:53.104393", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:53.679728", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:54.254691", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:54.844917", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:55.419869", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:56.010798", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:56.601861", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:57.160506", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:57.703530", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:58.278628", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:58.853555", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:59.412361", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:22:59.987060", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:00.562275", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:01.137066", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:01.695686", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:02.206878", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:02.717781", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:03.244702", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:03.755923", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:04.251892", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:04.714022", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:05.256949", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:05.784134", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:06.311212", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:06.838186", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:07.364957", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:07.908303", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:07.965016", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-13T09:23:07.966941", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-13T09:23:07.968861", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:23:07.971751", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-13T09:23:07.974960", "lvl": "input", "msg": "tftp 0x4a000000 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-13T09:23:08.077949", "lvl": "target", "msg": "=> tftp 0x4a000000 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:23:08.080124", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:23)"}
- {"dt": "2023-12-13T09:23:08.085219", "lvl": "target", "msg": "tftp 0x4a000000 34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:23:08.116270", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:23:08.118153", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:23:08.119573", "lvl": "target", "msg": "Filename '34218/nbd-deploy-fhqsmkb3/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-13T09:23:08.120746", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-13T09:23:08.662001", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-13T09:23:09.220769", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:23:09.327604", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-13T09:23:09.329482", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-13T09:23:09.331287", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:23:09.333047", "lvl": "target", "msg": "Bytes transferred = 2061461 (1f7495 hex)"}
- {"dt": "2023-12-13T09:23:09.336343", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-13T09:23:09.439267", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:23:09.441362", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:22)"}
- {"dt": "2023-12-13T09:23:09.457472", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:23:09.459062", "lvl": "input", "msg": "tftp 0x68000000 34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-13T09:23:09.561303", "lvl": "target", "msg": "=> tftp 0x68000000 34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:23:09.563468", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-12-13T09:23:09.568100", "lvl": "target", "msg": "tftp 0x68000000 34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:23:09.599420", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:23:09.600951", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:23:09.602993", "lvl": "target", "msg": "Filename '34218/nbd-deploy-fhqsmkb3/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-13T09:23:09.604542", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-13T09:23:09.650124", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-13T09:23:09.652083", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2023-12-13T09:23:09.653859", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:23:09.665986", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-13T09:23:09.667399", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61981 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-12-13T09:23:09.769465", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61981 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:23:09.771709", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-12-13T09:23:09.787834", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61981 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:23:09.789493", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-13T09:23:09.891638", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:23:09.893819", "lvl": "debug", "msg": "Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-12-13T09:23:09.895903", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-12-13T09:23:09.899922", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:23:09.915442", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-13T09:23:10.061754", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-13T09:23:10.064048", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-13T09:23:10.065662", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff495 ... OK"}
- {"dt": "2023-12-13T09:23:10.066992", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-13T09:23:10.068385", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-13T09:23:10.069694", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-13T09:23:10.071021", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-13T09:23:10.072408", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-13T09:23:10.073967", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-13T09:23:10.106693", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-13T09:23:10.107948", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-13T09:23:10.109753", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-13T09:23:10.111024", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-13T09:23:10.112250", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-13T09:23:10.113432", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-13T09:23:10.114621", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-13T09:23:10.115857", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-13T09:23:10.117036", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-13T09:23:10.118199", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-13T09:23:10.119570", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-13T09:23:10.127762", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-13T09:23:10.128375", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:23:10.131591", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-13T09:23:10.132569", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-13T09:23:10.133407", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:23:10.134912", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:23:10.135981", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:30) [common]"}
- {"dt": "2023-12-13T09:23:10.136923", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "29.79", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:23:10.138718", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:23:10.139765", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:36) [common]"}
- {"dt": "2023-12-13T09:23:10.140867", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "36.02", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:23:10.142604", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-13T09:23:10.143706", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:23:10.144375", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:39) [common]"}
- {"dt": "2023-12-13T09:23:10.145059", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "39.17", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:23:10.146405", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-13T09:23:10.147221", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/initrd"}
- {"dt": "2023-12-13T09:23:10.151023", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/kernel"}
- {"dt": "2023-12-13T09:23:10.160578", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/dtb"}
- {"dt": "2023-12-13T09:23:10.162028", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34218/nbd-deploy-fhqsmkb3/nbdroot"}
- {"dt": "2023-12-13T09:23:10.851742", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-13T09:23:10.852907", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-13T09:23:14.270357", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:23:14.307093", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-13T09:23:14.410526", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-13T09:23:14.412884", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.56", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:23:14.417248", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-13T09:23:14.420372", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-13T09:23:14.421433", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.422174", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.422815", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.423493", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.424124", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.424724", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.425401", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.426014", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.426688", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.427332", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.427955", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.428552", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.429140", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.429726", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.430307", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.430888", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.431523", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.432120", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.432711", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.433297", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.433882", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.434460", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.435044", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.435681", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.436278", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.439019", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.443546", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.444088", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.444588", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.445081", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.445571", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.446057", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.446536", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.447019", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.448780", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.449411", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.450005", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.450582", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.451184", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.451791", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.452377", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.452954", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.453527", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.454093", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.454656", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.455260", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.455858", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.456427", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.456986", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-13T09:23:14.457905", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-13T09:23:15.425233", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-13T09:23:15.427642", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-13T09:23:15.429626", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-13T09:23:15.533318", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:23:15.535513", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-13T09:23:15.537352", "lvl": "debug", "msg": "clean NBD port 61981"}
- {"dt": "2023-12-13T09:23:18.772629", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34218"}
- {"dt": "2023-12-13T09:23:18.867470", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34218"}
- {"dt": "2023-12-13T09:23:18.868579", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-13T09:23:18.869189", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
