- {"dt": "2023-12-01T10:59:22.912160", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-01T10:59:22.913542", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-01T10:59:22.914589", "lvl": "info", "msg": "Start time: 2023-12-01 10:59:22.914530+00:00 (UTC)"}
- {"dt": "2023-12-01T10:59:22.915822", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-01T10:59:23.820140", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-01T10:59:24.029359", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-01T10:59:24.247334", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-01T10:59:24.471562", "lvl": "info", "msg": "validate duration: 1.56"}
- {"dt": "2023-12-01T10:59:24.472434", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-01T10:59:24.473466", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-01T10:59:24.474055", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-01T10:59:24.474557", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-01T10:59:24.475013", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-01T10:59:24.475953", "lvl": "debug", "msg": "Set_port 61989"}
- {"dt": "2023-12-01T10:59:24.476827", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-01T10:59:24.477521", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-01T10:59:24.478625", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T10:59:24.479268", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T10:59:24.479766", "lvl": "debug", "msg": "total size: 2062724 (1MB)"}
- {"dt": "2023-12-01T10:59:24.480290", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-01T10:59:24.786184", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-01T10:59:24.983159", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-01T10:59:25.080471", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-01T10:59:25.093609", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-01T10:59:25.177889", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-01T10:59:25.185915", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-01T10:59:25.194320", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-01T10:59:25.198856", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-01T10:59:25.276602", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-01T10:59:25.285341", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-01T10:59:25.293729", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-01T10:59:25.299496", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-01T10:59:25.303891", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-01T10:59:25.309481", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-01T10:59:25.313913", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-01T10:59:25.318496", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-01T10:59:25.324163", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-01T10:59:25.379560", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-01T10:59:25.389918", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-01T10:59:25.393582", "lvl": "info", "msg": "1MB downloaded in 0.91s (2.15MB/s)"}
- {"dt": "2023-12-01T10:59:25.394835", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T10:59:25.395509", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.92", "extra": {"label": "initrd", "md5sum": "079e3a77066f067e66a9a654b4b9cefb", "sha256sum": "7bfefe10f58b99f6cbb3e6159f320826ee10bf0df20c6ece5ba7c855754d8575", "sha512sum": "347181222d46c6fc418325fc4342fc2ba470c3faed785fa3da73a151749004e3b9f55a566db64a160080b8c134d80fd5cf52b1682988bf894c25833d4134ab10", "size": ! "2062724"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T10:59:25.397148", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T10:59:25.397966", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-01T10:59:25.398754", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-01T10:59:25.399851", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-01T10:59:25.400587", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/kernel/Image"}
- {"dt": "2023-12-01T10:59:25.401158", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-01T10:59:25.401707", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-01T10:59:26.608126", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-01T10:59:28.544499", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-01T10:59:28.830257", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-01T10:59:29.229667", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-01T10:59:29.633225", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-01T10:59:30.023171", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-01T10:59:30.408560", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-01T10:59:30.787606", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-01T10:59:31.111313", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-01T10:59:31.495994", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-01T10:59:31.875348", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-01T10:59:32.191633", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-01T10:59:32.574662", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-01T10:59:32.893501", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-01T10:59:33.277652", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-01T10:59:33.639709", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-01T10:59:33.981545", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-01T10:59:34.345789", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-01T10:59:34.676653", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-01T10:59:35.041463", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-01T10:59:35.378192", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-01T10:59:35.380077", "lvl": "info", "msg": "37MB downloaded in 9.98s (3.75MB/s)"}
- {"dt": "2023-12-01T10:59:35.381487", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:10) [common]"}
- {"dt": "2023-12-01T10:59:35.382221", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "9.98", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T10:59:35.383898", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:10) [common]"}
- {"dt": "2023-12-01T10:59:35.384762", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-12-01T10:59:35.385562", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-12-01T10:59:35.386744", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T10:59:35.387518", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T10:59:35.388142", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-01T10:59:35.388847", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-01T10:59:35.692707", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-01T10:59:35.791173", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-01T10:59:35.797814", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-01T10:59:35.801055", "lvl": "info", "msg": "0MB downloaded in 0.41s (0.19MB/s)"}
- {"dt": "2023-12-01T10:59:35.802625", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T10:59:35.803378", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T10:59:35.804898", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T10:59:35.805686", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-12-01T10:59:35.806531", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-12-01T10:59:35.807709", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29479/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-01T10:59:35.808462", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-01T10:59:35.809058", "lvl": "debug", "msg": "total size: 453488672 (432MB)"}
- {"dt": "2023-12-01T10:59:35.809613", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-01T10:59:36.128146", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-01T10:59:42.190774", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-01T10:59:46.626199", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-01T10:59:51.937170", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-01T10:59:57.020848", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-01T11:00:04.917601", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-01T11:00:14.660323", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-01T11:00:23.640666", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-01T11:00:33.592721", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-01T11:00:41.630183", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-01T11:00:46.897292", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-01T11:00:52.832444", "lvl": "debug", "msg": "progress  55% (237MB)"}
- {"dt": "2023-12-01T11:00:58.071259", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-01T11:01:20.301848", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-01T11:01:41.193067", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-12-01T11:01:50.307912", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-01T11:01:56.217196", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-01T11:02:00.635793", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-01T11:02:05.851167", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-01T11:02:10.763040", "lvl": "debug", "msg": "progress  95% (410MB)"}
- {"dt": "2023-12-01T11:02:18.478602", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-01T11:02:22.109016", "lvl": "info", "msg": "432MB downloaded in 166.30s (2.60MB/s)"}
- {"dt": "2023-12-01T11:02:22.110944", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:46) [common]"}
- {"dt": "2023-12-01T11:02:22.111777", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "166.30", "extra": {"label": "nbdroot", "md5sum": "3dfb8dd367e4d13eb5bddfe1f7c334c3", "sha256sum": "a5bb1da7a04856fc9e69f17db8506b4adc8bf6661664d39aafbcaaeb88b29a10", "sha512sum": "c5797804490c4ac5e6aae228dbc06b34d2311b270c68109d48e96ec9db663899905fc366c1818fb4721421db1e9a08143f7e1740a3f47648838e9ee1b4733829", "size": ! "453488672"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:22.114058", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:46) [common]"}
- {"dt": "2023-12-01T11:02:22.115024", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.116722", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj"}
- {"dt": "2023-12-01T11:02:22.118000", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin"}
- {"dt": "2023-12-01T11:02:22.119093", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/tests"}
- {"dt": "2023-12-01T11:02:22.120155", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/results"}
- {"dt": "2023-12-01T11:02:22.121287", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-add-keys"}
- {"dt": "2023-12-01T11:02:22.122763", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-add-sources"}
- {"dt": "2023-12-01T11:02:22.124247", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-background-process-start"}
- {"dt": "2023-12-01T11:02:22.125716", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-background-process-stop"}
- {"dt": "2023-12-01T11:02:22.127075", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-common-functions"}
- {"dt": "2023-12-01T11:02:22.128496", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-echo-ipv4"}
- {"dt": "2023-12-01T11:02:22.129865", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-install-packages"}
- {"dt": "2023-12-01T11:02:22.131225", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-installed-packages"}
- {"dt": "2023-12-01T11:02:22.132754", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-os-build"}
- {"dt": "2023-12-01T11:02:22.134204", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-probe-channel"}
- {"dt": "2023-12-01T11:02:22.135639", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-probe-ip"}
- {"dt": "2023-12-01T11:02:22.137133", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-target-ip"}
- {"dt": "2023-12-01T11:02:22.138536", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-target-mac"}
- {"dt": "2023-12-01T11:02:22.139902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-target-storage"}
- {"dt": "2023-12-01T11:02:22.141322", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-case"}
- {"dt": "2023-12-01T11:02:22.142658", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-event"}
- {"dt": "2023-12-01T11:02:22.144006", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-feedback"}
- {"dt": "2023-12-01T11:02:22.145434", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-raise"}
- {"dt": "2023-12-01T11:02:22.146776", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-reference"}
- {"dt": "2023-12-01T11:02:22.148097", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-runner"}
- {"dt": "2023-12-01T11:02:22.149552", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-set"}
- {"dt": "2023-12-01T11:02:22.150911", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-test-shell"}
- {"dt": "2023-12-01T11:02:22.152358", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-01T11:02:22.153904", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-01T11:02:22.155289", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/environment"}
- {"dt": "2023-12-01T11:02:22.156468", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-01T11:02:22.157213", "lvl": "debug", "msg": "- LAVA_JOB_ID=33971"}
- {"dt": "2023-12-01T11:02:22.157790", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-01T11:02:22.158757", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.161263", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.162160", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.162825", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-01T11:02:22.163476", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.164268", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.164877", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-01T11:02:22.165596", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.166396", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.167075", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-01T11:02:22.167936", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.168734", "lvl": "debug", "msg": "Using /lava-33971 at stage 0"}
- {"dt": "2023-12-01T11:02:22.169685", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:22.170422", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/0/tests/0_network-veth'"}
- {"dt": "2023-12-01T11:02:22.745455", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-01T11:02:22.749736", "lvl": "debug", "msg": "uuid=33971_1.5.4.1 testdef=None"}
- {"dt": "2023-12-01T11:02:22.750909", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:22.751689", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "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-01T11:02:22.753229", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.758428", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.759179", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33971_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:22.760929", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.767427", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.768260", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33971_1.5.4.1", "uuid": "33971_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:22.769898", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.776154", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/0/tests/0_network-veth test_uuid 33971_1.5.4.1"}
- {"dt": "2023-12-01T11:02:22.777881", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:22.778618", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:22.780330", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:02) [common]"}
- {"dt": "2023-12-01T11:02:22.781143", "lvl": "debug", "msg": "Using /lava-33971 at stage 1"}
- {"dt": "2023-12-01T11:02:22.782227", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:22.783025", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-01T11:02:23.340298", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/1_ci-busybox"}
- {"dt": "2023-12-01T11:02:23.351422", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-01T11:02:23.354959", "lvl": "debug", "msg": "uuid=33971_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-01T11:02:23.356135", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:23.356968", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "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-01T11:02:23.358407", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-01T11:02:23.364745", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:23.365503", "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": "33971_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:23.367011", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-01T11:02:23.375179", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:23.375922", "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": "33971_1.5.4.5", "uuid": "33971_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:23.377623", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-01T11:02:23.385495", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/1_ci-busybox test_uuid 33971_1.5.4.5"}
- {"dt": "2023-12-01T11:02:23.387113", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:23.387827", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:23.389514", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:01) [common]"}
- {"dt": "2023-12-01T11:02:23.390271", "lvl": "debug", "msg": "Using /lava-33971 at stage 1"}
- {"dt": "2023-12-01T11:02:23.391276", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:23.392056", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-01T11:02:23.976374", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-01T11:02:23.987121", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-01T11:02:23.990169", "lvl": "debug", "msg": "uuid=33971_1.5.4.9 testdef=None"}
- {"dt": "2023-12-01T11:02:23.991322", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:23.992018", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "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-01T11:02:23.993461", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:23.998659", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:23.999343", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33971_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.000794", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.007530", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:24.008301", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33971_1.5.4.9", "uuid": "33971_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.009833", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.016527", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/2_ci-smoke-tests-basic test_uuid 33971_1.5.4.9"}
- {"dt": "2023-12-01T11:02:24.019280", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:24.020006", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.022042", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.022694", "lvl": "debug", "msg": "Using /lava-33971 at stage 1"}
- {"dt": "2023-12-01T11:02:24.023590", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:24.024339", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-01T11:02:24.599535", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/3_ci-service-check"}
- {"dt": "2023-12-01T11:02:24.610573", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-01T11:02:24.614448", "lvl": "debug", "msg": "uuid=33971_1.5.4.13 testdef=None"}
- {"dt": "2023-12-01T11:02:24.615557", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:24.616365", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "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-01T11:02:24.617865", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.623363", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:24.624096", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33971_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.625702", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.633209", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:24.633986", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33971_1.5.4.13", "uuid": "33971_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.635602", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.643506", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1/tests/3_ci-service-check test_uuid 33971_1.5.4.13"}
- {"dt": "2023-12-01T11:02:24.644344", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-01T11:02:24.644968", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-01T11:02:24.646399", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:24.647138", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:24.648879", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:00) [common]"}
- {"dt": "2023-12-01T11:02:24.649664", "lvl": "debug", "msg": "Using /lava-33971 at stage 2"}
- {"dt": "2023-12-01T11:02:24.650741", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:24.651563", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/2/tests/4_screenshooter'"}
- {"dt": "2023-12-01T11:02:25.227109", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-01T11:02:25.231260", "lvl": "debug", "msg": "uuid=33971_1.5.4.17 testdef=None"}
- {"dt": "2023-12-01T11:02:25.232524", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:25.233316", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "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-01T11:02:25.234794", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.239740", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.240531", "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": "33971_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.242092", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.248536", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.249290", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33971_1.5.4.17", "uuid": "33971_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.250859", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.257073", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/2/tests/4_screenshooter test_uuid 33971_1.5.4.17"}
- {"dt": "2023-12-01T11:02:25.258687", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.259434", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.261131", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.261859", "lvl": "debug", "msg": "Using /lava-33971 at stage 3"}
- {"dt": "2023-12-01T11:02:25.262854", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-01T11:02:25.263626", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-01T11:02:25.837003", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-01T11:02:25.847664", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-01T11:02:25.851530", "lvl": "debug", "msg": "uuid=33971_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-01T11:02:25.852652", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:25.853318", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "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-01T11:02:25.854642", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.860242", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.860945", "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": "33971_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.862583", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.871000", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.871693", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33971_1.5.4.21", "uuid": "33971_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.873249", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:25.880899", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/3/tests/5_ci-dumpjournal test_uuid 33971_1.5.4.21"}
- {"dt": "2023-12-01T11:02:25.882473", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:25.883173", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/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": "33971_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:25.884551", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-01T11:02:25.885076", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/0 for stage 0"}
- {"dt": "2023-12-01T11:02:25.885929", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-01T11:02:25.886706", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/1 for stage 1"}
- {"dt": "2023-12-01T11:02:25.887553", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-01T11:02:25.888145", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-01T11:02:25.888707", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-01T11:02:25.889327", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/2 for stage 2"}
- {"dt": "2023-12-01T11:02:25.890168", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-01T11:02:25.890890", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33971/lava-overlay-zmw8jbsj/lava-33971/3 for stage 3"}
- {"dt": "2023-12-01T11:02:25.891726", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-01T11:02:25.892742", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-01T11:02:25.893551", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-01T11:02:26.650234", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:02:26.651192", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:26:58) [common]"}
- {"dt": "2023-12-01T11:02:26.652555", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.653546", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-01T11:02:26.654490", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:26:58) [common]"}
- {"dt": "2023-12-01T11:02:26.655457", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:26:58) [common]"}
- {"dt": "2023-12-01T11:02:26.656537", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.657454", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.658363", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:26:58) [common]"}
- {"dt": "2023-12-01T11:02:26.659164", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-01T11:02:26.659900", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61989, NBD-ROOT: 33971/nbd-deploy-6bbw7lgd/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-01T11:02:26.663869", "lvl": "debug", "msg": "nbd-server 61989 /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-01T11:02:26.684140", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-01T11:02:26.686198", "lvl": "debug", "msg": "output: ** (process:18590): WARNING **: 11:02:26.682: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-01T11:02:26.686868", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-01T11:02:26.687539", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-01T11:02:26.688734", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.689836", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:03:02) [common]"}
- {"dt": "2023-12-01T11:02:26.690838", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-01T11:02:26.691824", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-01T11:02:26.692738", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.693676", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-01T11:02:26.695086", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-01T11:02:26.698445", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-01T11:02:26.699324", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-01T11:02:26.699909", "lvl": "debug", "msg": "- {DTB}: 33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T11:02:26.700499", "lvl": "debug", "msg": "- {INITRD}: 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T11:02:26.701081", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-01T11:02:26.701579", "lvl": "debug", "msg": "- {KERNEL}: 33971/nbd-deploy-6bbw7lgd/kernel/Image"}
- {"dt": "2023-12-01T11:02:26.702066", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-01T11:02:26.702552", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-01T11:02:26.703085", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61989"}
- {"dt": "2023-12-01T11:02:26.703705", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-01T11:02:26.704402", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-01T11:02:26.704964", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-01T11:02:26.705447", "lvl": "debug", "msg": "- {RAMDISK}: 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T11:02:26.705965", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-01T11:02:26.706442", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-01T11:02:26.708827", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-01T11:02:26.709477", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-01T11:02:26.709991", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-01T11:02:26.710474", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-01T11:02:26.710963", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-01T11:02:26.711459", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:26.711912", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:26.712462", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-01T11:02:26.712931", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-01T11:02:26.713413", "lvl": "info", "msg": "- tftp 0x68080000 33971/nbd-deploy-6bbw7lgd/kernel/Image"}
- {"dt": "2023-12-01T11:02:26.713920", "lvl": "info", "msg": "- tftp 0x4a000000 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T11:02:26.714423", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-01T11:02:26.714893", "lvl": "info", "msg": "- tftp 0x68000000 33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T11:02:26.715336", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-01T11:02:26.715884", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-01T11:02:26.718850", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-01T11:02:26.719729", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.03", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:26.721342", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-01T11:02:26.722021", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-01T11:02:30.087330", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-01T11:02:30.091142", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-01T11:02:30.091980", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-01T11:02:30.092771", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-01T11:02:30.093535", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-01T11:02:30.094658", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-01T11:02:33.525191", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-01T11:02:34.595087", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-01T11:02:34.630013", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-01T11:02:34.732320", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-01T11:02:34.733593", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.64", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:02:34.736470", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-01T11:02:34.737695", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-01T11:02:34.738657", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-01T11:02:34.740128", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-01T11:02:34.743228", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-01T11:02:34.744179", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-01T11:02:34.744838", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-01T11:02:34.806281", "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-01T11:02:34.807349", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:34.848441", "lvl": "target", "msg": "NOTICE:  BL\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:34.849590", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:34.931682", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Prem\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:34.932519", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:34.973470", "lvl": "target", "msg": "NOTICE:\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:34.974549", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.019984", "lvl": "target", "msg": "NOTICE:  BL2: Board is Star\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.021188", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.068045", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier R\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.069529", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.071386", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-01T11:02:35.118288", "lvl": "target", "msg": "NOTICE:  BL2:\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.119285", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.120234", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-01T11:02:35.172134", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFl\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.173284", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.174150", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-01T11:02:35.175514", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-01T11:02:35.229832", "lvl": "target", "msg": "NOTICE:  BL2: LCM stat\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.231131", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.232308", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-01T11:02:35.233338", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-01T11:02:35.234340", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-01T11:02:35.297310", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVF\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-01T11:02:35.298672", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-01T11:02:35.299726", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-01T11:02:35.300851", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-01T11:02:35.301863", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-01T11:02:35.302845", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-01T11:02:35.303838", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-01T11:02:35.304998", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-01T11:02:35.343856", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-01T11:02:35.344990", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-01T11:02:35.346006", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-01T11:02:35.346857", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-01T11:02:35.347682", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.348498", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.349077", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.349666", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.350259", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-01T11:02:35.351064", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-01T11:02:35.352299", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-01T11:02:35.387130", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-01T11:02:35.388315", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-01T11:02:35.389347", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-01T11:02:35.390347", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-01T11:02:35.391325", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-01T11:02:35.392355", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-01T11:02:35.393841", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-01T11:02:35.425686", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-01T11:02:35.427038", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-01T11:02:35.429113", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-01T11:02:35.757383", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:02:35.758499", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:02:35.759448", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-01T11:02:35.760421", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:02:35.762237", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-01T11:02:35.763299", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-01T11:02:35.951399", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-01T11:02:35.982173", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-01T11:02:35.983503", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-01T11:02:35.985317", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.986717", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.987794", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-01T11:02:35.988865", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:02:36.029140", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-01T11:02:36.195997", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-01T11:02:36.196790", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-01T11:02:36.197335", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-01T11:02:36.199382", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-01T11:02:36.247666", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-01T11:02:36.262882", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-01T11:02:36.314047", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-01T11:02:36.315631", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-01T11:02:36.316637", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:50) [common]"}
- {"dt": "2023-12-01T11:02:36.317384", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-01T11:02:36.318055", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:50)"}
- {"dt": "2023-12-01T11:02:36.342059", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-01T11:02:36.343495", "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-01T11:02:36.344300", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-01T11:02:36.445937", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-01T11:02:36.447141", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-01T11:02:36.454159", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-01T11:02:36.455248", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-01T11:02:36.556744", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:36.557693", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-01T11:02:36.566523", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:36.567571", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-01T11:02:36.669160", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:36.670115", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-01T11:02:36.677777", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-01T11:02:36.678843", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-01T11:02:36.780354", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-01T11:02:36.781287", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-01T11:02:36.789982", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-01T11:02:39.876156", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-01T11:02:39.891940", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-01T11:02:40.131757", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-01T11:02:40.643469", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-01T11:02:40.914526", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1028 ms)"}
- {"dt": "2023-12-01T11:02:40.916051", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-01T11:02:41.017959", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-01T11:02:41.019244", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-01T11:02:41.026184", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-01T11:02:41.027462", "lvl": "input", "msg": "tftp 0x68080000 33971/nbd-deploy-6bbw7lgd/kernel/Image\n"}
- {"dt": "2023-12-01T11:02:41.129369", "lvl": "target", "msg": "=> tftp 0x68080000 33971/nbd-deploy-6bbw7lgd/kernel/Image"}
- {"dt": "2023-12-01T11:02:41.130524", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-01T11:02:41.138326", "lvl": "target", "msg": "tftp 0x68080000 33971/nbd-deploy-6bbw7lgd/kernel/Image"}
- {"dt": "2023-12-01T11:02:41.163025", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-01T11:02:41.165059", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-01T11:02:41.166663", "lvl": "target", "msg": "Filename '33971/nbd-deploy-6bbw7lgd/kernel/Image'."}
- {"dt": "2023-12-01T11:02:41.167968", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-01T11:02:41.693894", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-01T11:02:42.220382", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:42.779988", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:43.324409", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:43.867146", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:44.426410", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:44.954144", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:45.481900", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:46.041633", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:46.585070", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:47.129769", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:47.657103", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:48.184782", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:48.760429", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:49.320004", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:49.846804", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:50.358777", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:50.902424", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:51.429834", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:51.989086", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:52.565085", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:53.108547", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:53.667890", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:54.228000", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:54.787409", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:55.346659", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:55.922133", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:56.497962", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:57.041301", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:57.584995", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:58.096352", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:58.607924", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:59.135813", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:02:59.648177", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:00.142699", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:00.671333", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:01.230967", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:01.758587", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:02.286041", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:02.829685", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:03.341252", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:03.413034", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-01T11:03:03.413955", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-01T11:03:03.414561", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-01T11:03:03.416769", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-01T11:03:03.418073", "lvl": "input", "msg": "tftp 0x4a000000 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-01T11:03:03.519741", "lvl": "target", "msg": "=> tftp 0x4a000000 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T11:03:03.520809", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:23)"}
- {"dt": "2023-12-01T11:03:03.527078", "lvl": "target", "msg": "tftp 0x4a000000 33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-01T11:03:03.557540", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-01T11:03:03.558742", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-01T11:03:03.559747", "lvl": "target", "msg": "Filename '33971/nbd-deploy-6bbw7lgd/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-01T11:03:03.561316", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-01T11:03:04.087978", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-01T11:03:04.583249", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-01T11:03:04.663314", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-01T11:03:04.664090", "lvl": "target", "msg": "\t 1.8 MiB/s"}
- {"dt": "2023-12-01T11:03:04.664811", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-01T11:03:04.678725", "lvl": "target", "msg": "Bytes transferred = 2062724 (1f7984 hex)"}
- {"dt": "2023-12-01T11:03:04.679873", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-01T11:03:04.781522", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-01T11:03:04.783577", "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-01T11:03:04.791183", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-01T11:03:04.792494", "lvl": "input", "msg": "tftp 0x68000000 33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-01T11:03:04.894477", "lvl": "target", "msg": "=> tftp 0x68000000 33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T11:03:04.895883", "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-01T11:03:04.901200", "lvl": "target", "msg": "tftp 0x68000000 33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-01T11:03:04.933193", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-01T11:03:04.933984", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-01T11:03:04.934624", "lvl": "target", "msg": "Filename '33971/nbd-deploy-6bbw7lgd/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-01T11:03:04.935216", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-01T11:03:04.987943", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-01T11:03:04.988784", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-01T11:03:04.989410", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-01T11:03:04.990008", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-01T11:03:04.991440", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-01T11:03:05.093530", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-01T11:03:05.094527", "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-01T11:03:05.111762", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-01T11:03:05.113091", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-01T11:03:05.214770", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-01T11:03:05.216077", "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-01T11:03:05.216972", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-12-01T11:03:05.223740", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-01T11:03:05.239759", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-01T11:03:05.385636", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-01T11:03:05.386441", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-01T11:03:05.387116", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff984 ... OK"}
- {"dt": "2023-12-01T11:03:05.387749", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-01T11:03:05.389507", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-01T11:03:05.390251", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-01T11:03:05.390902", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-01T11:03:05.391542", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-01T11:03:05.392219", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-01T11:03:05.393326", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-01T11:03:05.430671", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-01T11:03:05.431419", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-01T11:03:05.431987", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-01T11:03:05.432589", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-01T11:03:05.433118", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-01T11:03:05.434173", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-01T11:03:05.434768", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-01T11:03:05.435255", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-01T11:03:05.435750", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-01T11:03:05.436292", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-01T11:03:05.437194", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-01T11:03:05.437800", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:03:05.451399", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-01T11:03:05.454696", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-01T11:03:05.455445", "lvl": "target", "msg": ""}
- {"dt": "2023-12-01T11:03:05.456495", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-01T11:03:05.457236", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:29) [common]"}
- {"dt": "2023-12-01T11:03:05.457857", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "29.14", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-01T11:03:05.458950", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-01T11:03:05.459542", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:35) [common]"}
- {"dt": "2023-12-01T11:03:05.460287", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "35.37", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-01T11:03:05.461425", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-01T11:03:05.462146", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-01T11:03:05.462792", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:39) [common]"}
- {"dt": "2023-12-01T11:03:05.463404", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "38.77", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-01T11:03:05.464790", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-01T11:03:05.465578", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/initrd"}
- {"dt": "2023-12-01T11:03:05.467328", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/kernel"}
- {"dt": "2023-12-01T11:03:05.476646", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/dtb"}
- {"dt": "2023-12-01T11:03:05.478096", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33971/nbd-deploy-6bbw7lgd/nbdroot"}
- {"dt": "2023-12-01T11:03:05.699904", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-01T11:03:05.701255", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-01T11:03:09.152613", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-01T11:03:09.187944", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-01T11:03:09.291261", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-01T11:03:09.293001", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.59", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-01T11:03:09.295219", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-01T11:03:09.297943", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-01T11:03:09.301206", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.302317", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.303267", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.304272", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.304789", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.305361", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.305875", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.306375", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.306866", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.307419", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.307929", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.308462", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.308954", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.309440", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.310337", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.312322", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.314202", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.314822", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.315334", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.315841", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.316404", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.316907", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.317405", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.317901", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.318392", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.318888", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.319378", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.319877", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.320410", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.320908", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.321393", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.321874", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.322355", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.322841", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.323382", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.323871", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.324391", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.324880", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.325362", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.325848", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.326333", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.326871", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.327360", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.327879", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.328410", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.328900", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.329381", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.329907", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.330396", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-01T11:03:09.331234", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-01T11:03:10.300507", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-01T11:03:10.301371", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-01T11:03:10.302037", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-01T11:03:10.403957", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-01T11:03:10.405310", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-01T11:03:10.406805", "lvl": "debug", "msg": "clean NBD port 61989"}
- {"dt": "2023-12-01T11:03:13.808732", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33971"}
- {"dt": "2023-12-01T11:03:13.909673", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33971"}
- {"dt": "2023-12-01T11:03:13.910800", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-01T11:03:13.911570", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
