- {"dt": "2023-12-15T18:07:39.844329", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-15T18:07:39.845731", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-15T18:07:39.846835", "lvl": "info", "msg": "Start time: 2023-12-15 18:07:39.846771+00:00 (UTC)"}
- {"dt": "2023-12-15T18:07:39.848102", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-15T18:07:40.065878", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-15T18:07:40.272592", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-15T18:07:40.489550", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-15T18:07:40.712309", "lvl": "info", "msg": "validate duration: 0.87"}
- {"dt": "2023-12-15T18:07:40.713142", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-15T18:07:40.714736", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T18:07:40.715510", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-15T18:07:40.716130", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-15T18:07:40.716671", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-15T18:07:40.717658", "lvl": "debug", "msg": "Set_port 61987"}
- {"dt": "2023-12-15T18:07:40.718588", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T18:07:40.719781", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T18:07:40.721082", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:07:40.721815", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:07:40.722407", "lvl": "debug", "msg": "total size: 2061273 (1MB)"}
- {"dt": "2023-12-15T18:07:40.722927", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T18:07:41.025251", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-15T18:07:41.220018", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-15T18:07:41.319526", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-15T18:07:41.326325", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-15T18:07:41.419118", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-15T18:07:41.429881", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-15T18:07:41.436535", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-15T18:07:41.442019", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-15T18:07:41.515906", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-15T18:07:41.529974", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-15T18:07:41.537126", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-15T18:07:41.544137", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-15T18:07:41.549341", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-15T18:07:41.555865", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-15T18:07:41.560504", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-15T18:07:41.565607", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-15T18:07:41.571448", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-15T18:07:41.636737", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-15T18:07:41.643644", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-15T18:07:41.647289", "lvl": "info", "msg": "1MB downloaded in 0.92s (2.13MB/s)"}
- {"dt": "2023-12-15T18:07:41.648616", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:07:41.649331", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.93", "extra": {"label": "initrd", "md5sum": "7b9c6129ac5685832f04df1c36eb87f1", "sha256sum": "62bc48ece957f578515b703fcdbfcb145e27f9cec6880d09a84d69576852b9c3", "sha512sum": "0729aa7818a31c670370ddc96496cb71970bc3e7daf87bbfa3c6d0c68c22d0302e6549de64f6ac472b1ee270c41fd0ba1fe91f4a2def7b1a22b245e696fcfbd5", "size": ! "2061273"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:07:41.650985", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:07:41.651930", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-15T18:07:41.652862", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-15T18:07:41.653992", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-15T18:07:41.654732", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/kernel/Image"}
- {"dt": "2023-12-15T18:07:41.655711", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-15T18:07:41.656348", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T18:07:41.963375", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-15T18:07:42.649994", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-15T18:07:42.853441", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-15T18:07:43.147348", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-15T18:07:43.441459", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-15T18:07:43.735037", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-15T18:07:44.058123", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-15T18:07:44.531377", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-15T18:07:45.049760", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-15T18:07:45.710227", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-15T18:07:46.306422", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-15T18:07:46.905690", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-15T18:07:47.484669", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-15T18:07:48.066444", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-15T18:07:48.651121", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-15T18:07:49.232175", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-15T18:07:49.799667", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-15T18:07:50.374605", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-15T18:07:50.914023", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-15T18:07:51.497208", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-15T18:07:52.078126", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-15T18:07:52.079764", "lvl": "info", "msg": "37MB downloaded in 10.42s (3.59MB/s)"}
- {"dt": "2023-12-15T18:07:52.080987", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:10) [common]"}
- {"dt": "2023-12-15T18:07:52.081745", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "10.43", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:07:52.083408", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:10) [common]"}
- {"dt": "2023-12-15T18:07:52.084270", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-12-15T18:07:52.085188", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-12-15T18:07:52.086458", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:07:52.087714", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:07:52.088442", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-15T18:07:52.089102", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T18:07:52.390557", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-15T18:07:52.488289", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-15T18:07:52.490697", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-15T18:07:52.492300", "lvl": "info", "msg": "0MB downloaded in 0.40s (0.20MB/s)"}
- {"dt": "2023-12-15T18:07:52.493507", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:07:52.494317", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.41", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:07:52.496049", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:07:52.497065", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:48) [common]"}
- {"dt": "2023-12-15T18:07:52.497977", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:48) [common]"}
- {"dt": "2023-12-15T18:07:52.499603", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29540/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-15T18:07:52.500392", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T18:07:52.500960", "lvl": "debug", "msg": "total size: 454051232 (433MB)"}
- {"dt": "2023-12-15T18:07:52.501520", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-15T18:07:52.826112", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-15T18:08:00.515754", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-15T18:08:07.368014", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-15T18:08:14.943722", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-15T18:08:21.255749", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-15T18:08:29.380094", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-15T18:08:38.409721", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-15T18:08:46.657216", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-15T18:08:55.727640", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-15T18:09:02.767795", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-15T18:09:08.854482", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-15T18:09:14.791745", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-15T18:09:21.335210", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-15T18:09:27.395794", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-15T18:09:33.429638", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-15T18:09:38.923812", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-15T18:09:44.451624", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-15T18:09:49.731835", "lvl": "debug", "msg": "progress  85% (368MB)"}
- {"dt": "2023-12-15T18:09:55.231602", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-15T18:10:00.535779", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-15T18:10:12.604137", "lvl": "debug", "msg": "progress 100% (433MB)"}
- {"dt": "2023-12-15T18:10:18.359587", "lvl": "info", "msg": "433MB downloaded in 145.86s (2.97MB/s)"}
- {"dt": "2023-12-15T18:10:18.361545", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:26) [common]"}
- {"dt": "2023-12-15T18:10:18.362393", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "145.86", "extra": {"label": "nbdroot", "md5sum": "ffcca99010709ba3923e9c971b37dafe", "sha256sum": "79e4c9aa7410de0fe34505787812bda142063a8b0de42958a107d3486e8699f6", "sha512sum": "bd1bf2bb8a80d8af75a09cb196d6bda8ac2e761ab0184cb01ac7e46f82e172f9495e6310b66c774eed9fa332ecad51ad9bb1253497d396d1b90dbb8b68916a17", "size": ! "454051232"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:18.368664", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:26) [common]"}
- {"dt": "2023-12-15T18:10:18.369652", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.371896", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk"}
- {"dt": "2023-12-15T18:10:18.373312", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin"}
- {"dt": "2023-12-15T18:10:18.374437", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/tests"}
- {"dt": "2023-12-15T18:10:18.375647", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/results"}
- {"dt": "2023-12-15T18:10:18.376791", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-add-keys"}
- {"dt": "2023-12-15T18:10:18.378255", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-add-sources"}
- {"dt": "2023-12-15T18:10:18.379818", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-background-process-start"}
- {"dt": "2023-12-15T18:10:18.381262", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-background-process-stop"}
- {"dt": "2023-12-15T18:10:18.382636", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-common-functions"}
- {"dt": "2023-12-15T18:10:18.384570", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-echo-ipv4"}
- {"dt": "2023-12-15T18:10:18.386098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-install-packages"}
- {"dt": "2023-12-15T18:10:18.388082", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-installed-packages"}
- {"dt": "2023-12-15T18:10:18.389524", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-os-build"}
- {"dt": "2023-12-15T18:10:18.390902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-probe-channel"}
- {"dt": "2023-12-15T18:10:18.392518", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-probe-ip"}
- {"dt": "2023-12-15T18:10:18.399872", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-target-ip"}
- {"dt": "2023-12-15T18:10:18.401302", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-target-mac"}
- {"dt": "2023-12-15T18:10:18.402678", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-target-storage"}
- {"dt": "2023-12-15T18:10:18.406575", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-case"}
- {"dt": "2023-12-15T18:10:18.408118", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-event"}
- {"dt": "2023-12-15T18:10:18.420855", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-feedback"}
- {"dt": "2023-12-15T18:10:18.422263", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-raise"}
- {"dt": "2023-12-15T18:10:18.425775", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-reference"}
- {"dt": "2023-12-15T18:10:18.427252", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-runner"}
- {"dt": "2023-12-15T18:10:18.433222", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-set"}
- {"dt": "2023-12-15T18:10:18.434715", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-test-shell"}
- {"dt": "2023-12-15T18:10:18.436319", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-15T18:10:18.437929", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-15T18:10:18.442087", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/environment"}
- {"dt": "2023-12-15T18:10:18.443413", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-15T18:10:18.444297", "lvl": "debug", "msg": "- LAVA_JOB_ID=34330"}
- {"dt": "2023-12-15T18:10:18.444987", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-15T18:10:18.445992", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.451132", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:18.452138", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.452796", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-15T18:10:18.453691", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:18.454636", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.455470", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-15T18:10:18.456270", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:18.457086", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.457831", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-15T18:10:18.458693", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:22) [common]"}
- {"dt": "2023-12-15T18:10:18.459553", "lvl": "debug", "msg": "Using /lava-34330 at stage 0"}
- {"dt": "2023-12-15T18:10:18.462592", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:18.463466", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/0/tests/0_network-veth'"}
- {"dt": "2023-12-15T18:10:19.544771", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-15T18:10:19.557310", "lvl": "debug", "msg": "uuid=34330_1.5.4.1 testdef=None"}
- {"dt": "2023-12-15T18:10:19.572495", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:19.574214", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.11", "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-15T18:10:19.615465", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-15T18:10:19.632475", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:19.633493", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.02", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34330_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:19.644921", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-15T18:10:19.657532", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:19.660805", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "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": "34330_1.5.4.1", "uuid": "34330_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:19.673152", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-15T18:10:19.693102", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/0/tests/0_network-veth test_uuid 34330_1.5.4.1"}
- {"dt": "2023-12-15T18:10:19.701497", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:19.703809", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:19.707983", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:21) [common]"}
- {"dt": "2023-12-15T18:10:19.709483", "lvl": "debug", "msg": "Using /lava-34330 at stage 1"}
- {"dt": "2023-12-15T18:10:19.715418", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:19.717339", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-15T18:10:20.798909", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/1_ci-busybox"}
- {"dt": "2023-12-15T18:10:20.819460", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-15T18:10:20.824630", "lvl": "debug", "msg": "uuid=34330_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-15T18:10:20.825833", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:20.826585", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.12", "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-15T18:10:20.828226", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-15T18:10:20.837121", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:20.837866", "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": "34330_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:20.849089", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-15T18:10:20.860487", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:20.861262", "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": "34330_1.5.4.5", "uuid": "34330_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:20.862804", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-15T18:10:20.874689", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/1_ci-busybox test_uuid 34330_1.5.4.5"}
- {"dt": "2023-12-15T18:10:20.877280", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:20.884766", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:20.886816", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:20) [common]"}
- {"dt": "2023-12-15T18:10:20.888597", "lvl": "debug", "msg": "Using /lava-34330 at stage 1"}
- {"dt": "2023-12-15T18:10:20.889876", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:20.890685", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-15T18:10:21.755201", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-15T18:10:21.775723", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-15T18:10:21.784263", "lvl": "debug", "msg": "uuid=34330_1.5.4.9 testdef=None"}
- {"dt": "2023-12-15T18:10:21.787787", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:21.788698", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.90", "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-15T18:10:21.790235", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-15T18:10:21.808784", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:21.809555", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.02", "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": "34330_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:21.811112", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-15T18:10:21.854845", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:21.855654", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.04", "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": "34330_1.5.4.9", "uuid": "34330_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:21.857303", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-15T18:10:21.886004", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/2_ci-smoke-tests-basic test_uuid 34330_1.5.4.9"}
- {"dt": "2023-12-15T18:10:21.895387", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:21.896181", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.04", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:21.897856", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:19) [common]"}
- {"dt": "2023-12-15T18:10:21.898597", "lvl": "debug", "msg": "Using /lava-34330 at stage 1"}
- {"dt": "2023-12-15T18:10:21.903586", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:21.904600", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-15T18:10:22.983824", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/3_ci-service-check"}
- {"dt": "2023-12-15T18:10:23.009881", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-15T18:10:23.015851", "lvl": "debug", "msg": "uuid=34330_1.5.4.13 testdef=None"}
- {"dt": "2023-12-15T18:10:23.019693", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:23.021709", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.12", "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-15T18:10:23.035797", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-15T18:10:23.050137", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:23.050881", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.03", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34330_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:23.056910", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-15T18:10:23.088600", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:23.089378", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.03", "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": "34330_1.5.4.13", "uuid": "34330_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:23.090997", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-15T18:10:23.115867", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1/tests/3_ci-service-check test_uuid 34330_1.5.4.13"}
- {"dt": "2023-12-15T18:10:23.116735", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-15T18:10:23.118029", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-15T18:10:23.119604", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:23.120351", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:23.121936", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:18) [common]"}
- {"dt": "2023-12-15T18:10:23.122697", "lvl": "debug", "msg": "Using /lava-34330 at stage 2"}
- {"dt": "2023-12-15T18:10:23.132362", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:23.133264", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/2/tests/4_screenshooter'"}
- {"dt": "2023-12-15T18:10:24.093528", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-15T18:10:24.106477", "lvl": "debug", "msg": "uuid=34330_1.5.4.17 testdef=None"}
- {"dt": "2023-12-15T18:10:24.123874", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:24.124788", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.00", "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-15T18:10:24.126433", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-15T18:10:24.140879", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:24.141698", "lvl": "results", "msg": {"case": "test-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", "uuid": "34330_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:24.144926", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-15T18:10:24.167554", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:24.168396", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34330_1.5.4.17", "uuid": "34330_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:24.170107", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-15T18:10:24.199842", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/2/tests/4_screenshooter test_uuid 34330_1.5.4.17"}
- {"dt": "2023-12-15T18:10:24.214931", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:24.221037", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.04", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:24.222807", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:16) [common]"}
- {"dt": "2023-12-15T18:10:24.242300", "lvl": "debug", "msg": "Using /lava-34330 at stage 3"}
- {"dt": "2023-12-15T18:10:24.243461", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T18:10:24.244264", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-15T18:10:25.322012", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-15T18:10:25.346436", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-15T18:10:25.356117", "lvl": "debug", "msg": "uuid=34330_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-15T18:10:25.362672", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:25.363569", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.14", "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-15T18:10:25.365822", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:15) [common]"}
- {"dt": "2023-12-15T18:10:25.375935", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:25.377323", "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": "34330_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:25.378889", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:15) [common]"}
- {"dt": "2023-12-15T18:10:25.396960", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:25.398121", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "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": "34330_1.5.4.21", "uuid": "34330_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:25.399948", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:15) [common]"}
- {"dt": "2023-12-15T18:10:25.432780", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/3/tests/5_ci-dumpjournal test_uuid 34330_1.5.4.21"}
- {"dt": "2023-12-15T18:10:25.435587", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:25.436375", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.04", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/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": "34330_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:25.437813", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-15T18:10:25.438386", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/0 for stage 0"}
- {"dt": "2023-12-15T18:10:25.458154", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-15T18:10:25.459123", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/1 for stage 1"}
- {"dt": "2023-12-15T18:10:25.460388", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-15T18:10:25.461163", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-15T18:10:25.462174", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-15T18:10:25.462981", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/2 for stage 2"}
- {"dt": "2023-12-15T18:10:25.473844", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-15T18:10:25.474884", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34330/lava-overlay-2za615lk/lava-34330/3 for stage 3"}
- {"dt": "2023-12-15T18:10:25.476584", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-15T18:10:25.478137", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:07) [common]"}
- {"dt": "2023-12-15T18:10:25.479116", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:15) [common]"}
- {"dt": "2023-12-15T18:10:26.750913", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:10:26.752275", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-12-15T18:10:26.754071", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.757144", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:08) [common]"}
- {"dt": "2023-12-15T18:10:26.758168", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:14) [common]"}
- {"dt": "2023-12-15T18:10:26.759032", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:14) [common]"}
- {"dt": "2023-12-15T18:10:26.759980", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.760775", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.761554", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:14) [common]"}
- {"dt": "2023-12-15T18:10:26.762235", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-15T18:10:26.762828", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61987, NBD-ROOT: 34330/nbd-deploy-uet6can1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T18:10:26.770318", "lvl": "debug", "msg": "nbd-server 61987 /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T18:10:26.814518", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-15T18:10:26.816706", "lvl": "debug", "msg": "output: ** (process:13713): WARNING **: 18:10:26.809: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-15T18:10:26.823801", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-15T18:10:26.828898", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-15T18:10:26.830002", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.830969", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:46) [common]"}
- {"dt": "2023-12-15T18:10:26.844249", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T18:10:26.850670", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T18:10:26.855000", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.856089", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T18:10:26.885974", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-15T18:10:26.886740", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T18:10:26.894947", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-15T18:10:26.896349", "lvl": "debug", "msg": "- {DTB}: 34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:10:26.896965", "lvl": "debug", "msg": "- {INITRD}: 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:10:26.897526", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-15T18:10:26.898043", "lvl": "debug", "msg": "- {KERNEL}: 34330/nbd-deploy-uet6can1/kernel/Image"}
- {"dt": "2023-12-15T18:10:26.898827", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-15T18:10:26.907202", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-15T18:10:26.907854", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61987"}
- {"dt": "2023-12-15T18:10:26.908402", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-15T18:10:26.909384", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-15T18:10:26.910003", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-15T18:10:26.910533", "lvl": "debug", "msg": "- {RAMDISK}: 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:10:26.921376", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-15T18:10:26.921980", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-15T18:10:26.922503", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-15T18:10:26.923057", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-15T18:10:26.928655", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-15T18:10:26.929249", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-15T18:10:26.929755", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-15T18:10:26.930244", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:26.930735", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:26.939503", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-15T18:10:26.940158", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T18:10:26.940778", "lvl": "info", "msg": "- tftp 0x68080000 34330/nbd-deploy-uet6can1/kernel/Image"}
- {"dt": "2023-12-15T18:10:26.941428", "lvl": "info", "msg": "- tftp 0x4a000000 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:10:26.941973", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T18:10:26.942527", "lvl": "info", "msg": "- tftp 0x68000000 34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:10:26.943020", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61987 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-15T18:10:26.947877", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T18:10:26.951287", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T18:10:26.952054", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.10", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:26.953596", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T18:10:26.954188", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-15T18:10:30.864328", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-15T18:10:30.866425", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:04) [common]"}
- {"dt": "2023-12-15T18:10:30.870990", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:56) [common]"}
- {"dt": "2023-12-15T18:10:30.872046", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:56) [common]"}
- {"dt": "2023-12-15T18:10:30.872866", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:56) [common]"}
- {"dt": "2023-12-15T18:10:30.874143", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-15T18:10:34.874816", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T18:10:35.947772", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T18:10:36.027835", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-12-15T18:10:36.130074", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T18:10:36.131033", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.26", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:10:36.136655", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T18:10:36.137544", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:51) [common]"}
- {"dt": "2023-12-15T18:10:36.138387", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-15T18:10:36.139063", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-15T18:10:36.144713", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-15T18:10:36.145455", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-15T18:10:36.146117", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-15T18:10:36.162054", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Init\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.162806", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.206085", "lvl": "target", "msg": "NOTICE:  BL2\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.206902", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.286546", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Prem\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.287411", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.329641", "lvl": "target", "msg": "NOTICE\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.330902", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.382438", "lvl": "target", "msg": "NOTICE:  BL2: Board is Star\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.383264", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.430743", "lvl": "target", "msg": "NOTICE:  BL2: Board is \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.431628", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.434162", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T18:10:36.481922", "lvl": "target", "msg": "NOTICE:  \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.482785", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.483576", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T18:10:36.535554", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.536371", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.536924", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T18:10:36.539749", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T18:10:36.599430", "lvl": "target", "msg": "NOTICE:  BL2: LCM state i\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.600185", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.600703", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T18:10:36.601207", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T18:10:36.601692", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-15T18:10:36.602861", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-15T18:10:36.672646", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T18:10:36.673426", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T18:10:36.673978", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T18:10:36.674456", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T18:10:36.674882", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-15T18:10:36.681642", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-15T18:10:36.682248", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-15T18:10:36.684567", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-15T18:10:36.711965", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-15T18:10:36.712741", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-15T18:10:36.713723", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-15T18:10:36.714358", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-15T18:10:36.714879", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:36.715495", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:36.716041", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:36.716571", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:36.717107", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-15T18:10:36.717618", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-15T18:10:36.719489", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-15T18:10:36.753670", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-15T18:10:36.754494", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-15T18:10:36.755108", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-15T18:10:36.759001", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-15T18:10:36.759933", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-15T18:10:36.760506", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-15T18:10:36.761807", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-15T18:10:36.792974", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-15T18:10:36.793767", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-15T18:10:36.800512", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-15T18:10:37.123330", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:10:37.124135", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:10:37.124771", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-15T18:10:37.125347", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:10:37.127336", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-15T18:10:37.130590", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-15T18:10:37.318600", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-15T18:10:37.342280", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-15T18:10:37.343441", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-15T18:10:37.344117", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:37.345617", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:37.346473", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-15T18:10:37.347266", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:10:37.395663", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-15T18:10:37.561343", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-15T18:10:37.562150", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-15T18:10:37.562761", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-15T18:10:37.568172", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-15T18:10:37.611526", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-15T18:10:37.629721", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-15T18:10:37.683319", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-15T18:10:37.685152", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-15T18:10:37.686176", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:49) [common]"}
- {"dt": "2023-12-15T18:10:37.686949", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-15T18:10:37.688334", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:49)"}
- {"dt": "2023-12-15T18:10:37.708210", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-15T18:10:37.712139", "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-15T18:10:37.712907", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-15T18:10:37.815308", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-15T18:10:37.816243", "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:49)"}
- {"dt": "2023-12-15T18:10:37.835537", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-15T18:10:37.837502", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-15T18:10:37.939045", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:37.940248", "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:49)"}
- {"dt": "2023-12-15T18:10:37.947710", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:37.948781", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-15T18:10:38.051289", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:38.052178", "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:49)"}
- {"dt": "2023-12-15T18:10:38.059393", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T18:10:38.060692", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-15T18:10:38.163294", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-15T18:10:38.164203", "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:49)"}
- {"dt": "2023-12-15T18:10:38.171251", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-15T18:10:41.271469", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-15T18:10:41.272205", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-15T18:10:41.527516", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-15T18:10:42.018984", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-15T18:10:42.306316", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1031 ms)"}
- {"dt": "2023-12-15T18:10:42.311423", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-15T18:10:42.415336", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T18:10:42.416541", "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:44)"}
- {"dt": "2023-12-15T18:10:42.435568", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T18:10:42.439414", "lvl": "input", "msg": "tftp 0x68080000 34330/nbd-deploy-uet6can1/kernel/Image\n"}
- {"dt": "2023-12-15T18:10:42.543443", "lvl": "target", "msg": "=> tftp 0x68080000 34330/nbd-deploy-uet6can1/kernel/Image"}
- {"dt": "2023-12-15T18:10:42.545455", "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:44)"}
- {"dt": "2023-12-15T18:10:42.584304", "lvl": "target", "msg": "tftp 0x68080000 34330/nbd-deploy-uet6can1/kernel/Image"}
- {"dt": "2023-12-15T18:10:42.585235", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T18:10:42.585982", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T18:10:42.598930", "lvl": "target", "msg": "Filename '34330/nbd-deploy-uet6can1/kernel/Image'."}
- {"dt": "2023-12-15T18:10:42.599694", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-15T18:10:43.291371", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-15T18:10:43.851377", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:44.443381", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:44.954715", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:45.639786", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:46.151354", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:46.694973", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:47.303343", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:47.782157", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:48.245801", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:48.790192", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:49.284990", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:49.812481", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:50.324550", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:50.836670", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:51.347833", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:51.843676", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:52.387482", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:52.898221", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:53.505560", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:53.986176", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:54.465882", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:54.961402", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:55.441189", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:55.920572", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:56.448322", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:56.975527", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:57.534608", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:58.029932", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:58.542449", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:59.038102", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:10:59.580571", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:00.091892", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:00.620062", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:01.131183", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:01.658619", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:02.202148", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:02.761417", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:03.304834", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:03.864348", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:04.407431", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:04.463718", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-15T18:11:04.464456", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-15T18:11:04.464918", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T18:11:04.467008", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-15T18:11:04.468338", "lvl": "input", "msg": "tftp 0x4a000000 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-15T18:11:04.569612", "lvl": "target", "msg": "=> tftp 0x4a000000 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:11:04.570484", "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-15T18:11:04.575795", "lvl": "target", "msg": "tftp 0x4a000000 34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T18:11:04.606772", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T18:11:04.607550", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T18:11:04.608034", "lvl": "target", "msg": "Filename '34330/nbd-deploy-uet6can1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-15T18:11:04.610133", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-15T18:11:05.136186", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-15T18:11:05.711520", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T18:11:05.807946", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-15T18:11:05.809857", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-15T18:11:05.811414", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T18:11:05.823420", "lvl": "target", "msg": "Bytes transferred = 2061273 (1f73d9 hex)"}
- {"dt": "2023-12-15T18:11:05.825116", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-15T18:11:05.927217", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T18:11:05.929176", "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-15T18:11:05.935891", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T18:11:05.937094", "lvl": "input", "msg": "tftp 0x68000000 34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-15T18:11:06.038594", "lvl": "target", "msg": "=> tftp 0x68000000 34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:11:06.040605", "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-15T18:11:06.045790", "lvl": "target", "msg": "tftp 0x68000000 34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T18:11:06.076968", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T18:11:06.077933", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T18:11:06.079672", "lvl": "target", "msg": "Filename '34330/nbd-deploy-uet6can1/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-15T18:11:06.080883", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-15T18:11:06.139025", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-15T18:11:06.141013", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2023-12-15T18:11:06.142479", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T18:11:06.144095", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-15T18:11:06.146488", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61987 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-15T18:11:06.249433", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61987 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-15T18:11:06.251758", "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-15T18:11:06.269215", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61987 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-15T18:11:06.270774", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-15T18:11:06.373374", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T18:11:06.375673", "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-15T18:11:06.377589", "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:20)"}
- {"dt": "2023-12-15T18:11:06.381010", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T18:11:06.396663", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-15T18:11:06.543754", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-15T18:11:06.545945", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-15T18:11:06.547598", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff3d9 ... OK"}
- {"dt": "2023-12-15T18:11:06.549807", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-15T18:11:06.551416", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-15T18:11:06.552499", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-15T18:11:06.553490", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-15T18:11:06.554478", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-15T18:11:06.555538", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-15T18:11:06.588531", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-15T18:11:06.590546", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-15T18:11:06.592355", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-15T18:11:06.594865", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-15T18:11:06.596527", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-15T18:11:06.598161", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-15T18:11:06.599604", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-15T18:11:06.600109", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-15T18:11:06.600533", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-15T18:11:06.600949", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-15T18:11:06.601364", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-15T18:11:06.609169", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-15T18:11:06.609838", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:11:06.611567", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-15T18:11:06.612784", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-15T18:11:06.613492", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T18:11:06.614714", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T18:11:06.615689", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:29) [common]"}
- {"dt": "2023-12-15T18:11:06.616456", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "28.93", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T18:11:06.617965", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T18:11:06.618773", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:36) [common]"}
- {"dt": "2023-12-15T18:11:06.619667", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "35.75", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T18:11:06.621133", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-15T18:11:06.622061", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T18:11:06.622839", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:40) [common]"}
- {"dt": "2023-12-15T18:11:06.623607", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "39.78", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T18:11:06.624804", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-15T18:11:06.625495", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/initrd"}
- {"dt": "2023-12-15T18:11:06.627345", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/kernel"}
- {"dt": "2023-12-15T18:11:06.636448", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/dtb"}
- {"dt": "2023-12-15T18:11:06.637922", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34330/nbd-deploy-uet6can1/nbdroot"}
- {"dt": "2023-12-15T18:11:06.816671", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-15T18:11:06.817810", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-15T18:11:10.376778", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T18:11:10.410329", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-15T18:11:10.512544", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-15T18:11:10.513504", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.70", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T18:11:10.516310", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-15T18:11:10.520541", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-15T18:11:10.522270", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.523537", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.524065", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.524584", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.525073", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.525579", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.526080", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.526571", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.527058", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.527586", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.528084", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.528670", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.529175", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.529655", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.530166", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.530762", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.531426", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.531941", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.532440", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.532917", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.533391", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.533865", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.534345", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.534834", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.535355", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.535839", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.536329", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.536821", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.537451", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.538076", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.538674", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.539385", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.540021", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.540636", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.541263", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.541918", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.542597", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.543227", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.543718", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.544196", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.544675", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.545147", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.545664", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.546280", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.546808", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.547337", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.547815", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.548290", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.548760", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-15T18:11:10.549582", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-15T18:11:11.524050", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-15T18:11:11.525824", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-15T18:11:11.527405", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-15T18:11:11.630206", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T18:11:11.631541", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-15T18:11:11.632285", "lvl": "debug", "msg": "clean NBD port 61987"}
- {"dt": "2023-12-15T18:11:15.000921", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34330"}
- {"dt": "2023-12-15T18:11:15.093708", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34330"}
- {"dt": "2023-12-15T18:11:15.094819", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-15T18:11:15.095884", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
