- {"dt": "2023-12-07T05:38:24.851140", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-07T05:38:24.852529", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-07T05:38:24.853572", "lvl": "info", "msg": "Start time: 2023-12-07 05:38:24.853515+00:00 (UTC)"}
- {"dt": "2023-12-07T05:38:24.854707", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-07T05:38:25.079613", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-07T05:38:25.296548", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-07T05:38:25.504911", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-07T05:38:25.735912", "lvl": "info", "msg": "validate duration: 0.88"}
- {"dt": "2023-12-07T05:38:25.736761", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-07T05:38:25.737913", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:38:25.738581", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-07T05:38:25.739197", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-07T05:38:25.739763", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-07T05:38:25.740801", "lvl": "debug", "msg": "Set_port 61986"}
- {"dt": "2023-12-07T05:38:25.741740", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:38:25.742583", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:38:25.743865", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:38:25.744608", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:38:25.745222", "lvl": "debug", "msg": "total size: 2062759 (1MB)"}
- {"dt": "2023-12-07T05:38:25.745760", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:38:26.064794", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-07T05:38:26.270982", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-07T05:38:26.373631", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-07T05:38:26.475464", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-07T05:38:26.480402", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-07T05:38:26.580896", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-07T05:38:26.682582", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-07T05:38:26.687859", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-07T05:38:26.790620", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-07T05:38:26.889108", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-07T05:38:26.986504", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-07T05:38:26.996534", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-07T05:38:27.094934", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-07T05:38:27.194743", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-07T05:38:27.202549", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-07T05:38:27.300386", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-07T05:38:27.399538", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-07T05:38:27.409731", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-07T05:38:27.510995", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-07T05:38:27.517702", "lvl": "info", "msg": "1MB downloaded in 1.77s (1.11MB/s)"}
- {"dt": "2023-12-07T05:38:27.519393", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2023-12-07T05:38:27.520476", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.78", "extra": {"label": "initrd", "md5sum": "56ce2a8b0db753392e272c977325eb7c", "sha256sum": "7f1e4f566b4f7a1c95ff820f17324636b39c1f277040a5142557a356f2693332", "sha512sum": "b28fb63491201af0511390711bd10655c4aa2326f95c6cbf6fb602bb4217f342206ecbfdfd71bf83772c6207707711ce0e0eb3e713d0a9be10190c0e9f33b7b6", "size": ! "2062759"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:38:27.522021", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:02) [common]"}
- {"dt": "2023-12-07T05:38:27.522854", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:58) [common]"}
- {"dt": "2023-12-07T05:38:27.523647", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:58) [common]"}
- {"dt": "2023-12-07T05:38:27.524794", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-07T05:38:27.525475", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/kernel/Image"}
- {"dt": "2023-12-07T05:38:27.526028", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-07T05:38:27.526578", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:38:27.835789", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-07T05:38:29.200513", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-07T05:38:30.095049", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-07T05:38:30.864780", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-07T05:38:31.463441", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-07T05:38:31.960838", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-07T05:38:32.435160", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-07T05:38:32.832774", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-07T05:38:33.198927", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-07T05:38:33.520275", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-07T05:38:33.818640", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-07T05:38:34.113762", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-07T05:38:34.369028", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-07T05:38:34.612063", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-07T05:38:34.842850", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-07T05:38:35.063383", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-07T05:38:35.278671", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-07T05:38:35.478017", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-07T05:38:35.660763", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-07T05:38:35.836931", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-07T05:38:36.008609", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-07T05:38:36.010312", "lvl": "info", "msg": "37MB downloaded in 8.48s (4.41MB/s)"}
- {"dt": "2023-12-07T05:38:36.011570", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:08) [common]"}
- {"dt": "2023-12-07T05:38:36.012363", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "8.49", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:38:36.013841", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:08) [common]"}
- {"dt": "2023-12-07T05:38:36.014598", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:50) [common]"}
- {"dt": "2023-12-07T05:38:36.015373", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:50) [common]"}
- {"dt": "2023-12-07T05:38:36.016488", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:38:36.017194", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:38:36.017746", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-07T05:38:36.018284", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:38:36.339089", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-07T05:38:36.439580", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-07T05:38:36.446124", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-07T05:38:36.450336", "lvl": "info", "msg": "0MB downloaded in 0.43s (0.18MB/s)"}
- {"dt": "2023-12-07T05:38:36.452718", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:38:36.453468", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.44", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:38:36.454925", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:38:36.455691", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-12-07T05:38:36.456608", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-12-07T05:38:36.457698", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29507/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-07T05:38:36.458375", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:38:36.458925", "lvl": "debug", "msg": "total size: 453711412 (432MB)"}
- {"dt": "2023-12-07T05:38:36.459464", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-07T05:38:36.777615", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-07T05:38:42.996571", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-07T05:38:47.586535", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-07T05:38:52.737891", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-07T05:38:57.256089", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-07T05:39:04.000641", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-07T05:39:10.168590", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-07T05:39:20.346212", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-07T05:39:28.004025", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-07T05:39:34.495704", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-07T05:39:39.386108", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-07T05:39:44.666070", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-07T05:39:49.692871", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-07T05:39:54.455161", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-07T05:39:59.629388", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-12-07T05:40:06.948810", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-07T05:40:13.143747", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-07T05:40:17.799331", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-07T05:40:22.281749", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-07T05:40:26.535441", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-07T05:40:34.051013", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-07T05:40:37.264880", "lvl": "info", "msg": "432MB downloaded in 120.81s (3.58MB/s)"}
- {"dt": "2023-12-07T05:40:37.266905", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:01) [common]"}
- {"dt": "2023-12-07T05:40:37.267752", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "120.81", "extra": {"label": "nbdroot", "md5sum": "fd7a91019a6a09626575df40349559ef", "sha256sum": "369d45fbe453c4ff2947c69bb2d490169915b5ffeb662ffa30a40e497a31d223", "sha512sum": "30634e215fa123bb25ab6c621ef199fb015f900d892cfd71ab2e8cacd07f6cb2b559d9eedc9825a09ee3c902a86dd7a80ed0119c0602b31497ca6f03fbc30d9d", "size": ! "453711412"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:37.269606", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:01) [common]"}
- {"dt": "2023-12-07T05:40:37.270443", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.272034", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w"}
- {"dt": "2023-12-07T05:40:37.273573", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin"}
- {"dt": "2023-12-07T05:40:37.274895", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/tests"}
- {"dt": "2023-12-07T05:40:37.275959", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/results"}
- {"dt": "2023-12-07T05:40:37.276990", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-add-keys"}
- {"dt": "2023-12-07T05:40:37.278254", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-add-sources"}
- {"dt": "2023-12-07T05:40:37.279494", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-background-process-start"}
- {"dt": "2023-12-07T05:40:37.280805", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-background-process-stop"}
- {"dt": "2023-12-07T05:40:37.282016", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-common-functions"}
- {"dt": "2023-12-07T05:40:37.283175", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-echo-ipv4"}
- {"dt": "2023-12-07T05:40:37.284396", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-install-packages"}
- {"dt": "2023-12-07T05:40:37.285638", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-installed-packages"}
- {"dt": "2023-12-07T05:40:37.287146", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-os-build"}
- {"dt": "2023-12-07T05:40:37.288608", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-probe-channel"}
- {"dt": "2023-12-07T05:40:37.289876", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-probe-ip"}
- {"dt": "2023-12-07T05:40:37.291055", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-target-ip"}
- {"dt": "2023-12-07T05:40:37.292285", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-target-mac"}
- {"dt": "2023-12-07T05:40:37.293697", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-target-storage"}
- {"dt": "2023-12-07T05:40:37.295013", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-case"}
- {"dt": "2023-12-07T05:40:37.296749", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-event"}
- {"dt": "2023-12-07T05:40:37.298232", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-feedback"}
- {"dt": "2023-12-07T05:40:37.299490", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-raise"}
- {"dt": "2023-12-07T05:40:37.300802", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-reference"}
- {"dt": "2023-12-07T05:40:37.302057", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-runner"}
- {"dt": "2023-12-07T05:40:37.303256", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-set"}
- {"dt": "2023-12-07T05:40:37.304514", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-test-shell"}
- {"dt": "2023-12-07T05:40:37.305772", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-07T05:40:37.307194", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-07T05:40:37.308552", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/environment"}
- {"dt": "2023-12-07T05:40:37.309763", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-07T05:40:37.310531", "lvl": "debug", "msg": "- LAVA_JOB_ID=34031"}
- {"dt": "2023-12-07T05:40:37.311041", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-07T05:40:37.311935", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.314257", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.315115", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.315803", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-07T05:40:37.316774", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.317558", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.318075", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-07T05:40:37.318689", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.319365", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.319992", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-07T05:40:37.320829", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.321437", "lvl": "debug", "msg": "Using /lava-34031 at stage 0"}
- {"dt": "2023-12-07T05:40:37.322251", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:37.322963", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/0/tests/0_network-veth'"}
- {"dt": "2023-12-07T05:40:37.891130", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-07T05:40:37.895513", "lvl": "debug", "msg": "uuid=34031_1.5.4.1 testdef=None"}
- {"dt": "2023-12-07T05:40:37.896674", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:37.897512", "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-07T05:40:37.899204", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.904259", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.905073", "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": "34031_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:37.906664", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.912769", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.913485", "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": "34031_1.5.4.1", "uuid": "34031_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:37.914948", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.921324", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/0/tests/0_network-veth test_uuid 34031_1.5.4.1"}
- {"dt": "2023-12-07T05:40:37.922878", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:37.923582", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:37.925220", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:40:37.925997", "lvl": "debug", "msg": "Using /lava-34031 at stage 1"}
- {"dt": "2023-12-07T05:40:37.927001", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:37.927811", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-07T05:40:38.520603", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/1_ci-busybox"}
- {"dt": "2023-12-07T05:40:38.531465", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-07T05:40:38.534956", "lvl": "debug", "msg": "uuid=34031_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-07T05:40:38.536114", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:38.536979", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-07T05:40:38.538474", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:38.544767", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:38.545539", "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": "34031_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:38.547103", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:38.555581", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:38.556393", "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": "34031_1.5.4.5", "uuid": "34031_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:38.558055", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:38.565971", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/1_ci-busybox test_uuid 34031_1.5.4.5"}
- {"dt": "2023-12-07T05:40:38.567603", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:38.568418", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:38.570062", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:38.570843", "lvl": "debug", "msg": "Using /lava-34031 at stage 1"}
- {"dt": "2023-12-07T05:40:38.571844", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:38.572670", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-07T05:40:39.173238", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-07T05:40:39.186391", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-07T05:40:39.189667", "lvl": "debug", "msg": "uuid=34031_1.5.4.9 testdef=None"}
- {"dt": "2023-12-07T05:40:39.190695", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:39.191318", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.62", "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-07T05:40:39.193127", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:39.198211", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.198867", "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": "34031_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.200311", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:39.207076", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.207749", "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": "34031_1.5.4.9", "uuid": "34031_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.209260", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:39.215783", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/2_ci-smoke-tests-basic test_uuid 34031_1.5.4.9"}
- {"dt": "2023-12-07T05:40:39.218456", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.219166", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.220692", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:40:39.221322", "lvl": "debug", "msg": "Using /lava-34031 at stage 1"}
- {"dt": "2023-12-07T05:40:39.222186", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:39.222821", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-07T05:40:39.793942", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/3_ci-service-check"}
- {"dt": "2023-12-07T05:40:39.804637", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-07T05:40:39.808427", "lvl": "debug", "msg": "uuid=34031_1.5.4.13 testdef=None"}
- {"dt": "2023-12-07T05:40:39.809524", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:39.810265", "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-07T05:40:39.811795", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-07T05:40:39.817438", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.818185", "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": "34031_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.819845", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-07T05:40:39.827932", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.828732", "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": "34031_1.5.4.13", "uuid": "34031_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.830344", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-07T05:40:39.837612", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1/tests/3_ci-service-check test_uuid 34031_1.5.4.13"}
- {"dt": "2023-12-07T05:40:39.838379", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-07T05:40:39.838944", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-07T05:40:39.840369", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:39.841110", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:39.842761", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:46) [common]"}
- {"dt": "2023-12-07T05:40:39.843540", "lvl": "debug", "msg": "Using /lava-34031 at stage 2"}
- {"dt": "2023-12-07T05:40:39.844610", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:39.845384", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/2/tests/4_screenshooter'"}
- {"dt": "2023-12-07T05:40:40.421903", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-07T05:40:40.425666", "lvl": "debug", "msg": "uuid=34031_1.5.4.17 testdef=None"}
- {"dt": "2023-12-07T05:40:40.426833", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:40.427611", "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-07T05:40:40.429261", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:40.434584", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:40.435398", "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": "34031_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:40.437139", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:40.443617", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:40.444445", "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": "34031_1.5.4.17", "uuid": "34031_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:40.446170", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:40.452399", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/2/tests/4_screenshooter test_uuid 34031_1.5.4.17"}
- {"dt": "2023-12-07T05:40:40.454010", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:40.454749", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:40.456551", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:40.457440", "lvl": "debug", "msg": "Using /lava-34031 at stage 3"}
- {"dt": "2023-12-07T05:40:40.458596", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:40:40.459454", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-07T05:40:41.137218", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-07T05:40:41.148825", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-07T05:40:41.152068", "lvl": "debug", "msg": "uuid=34031_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-07T05:40:41.153341", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:41.154122", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.70", "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-07T05:40:41.155604", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:41.161811", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.162525", "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": "34031_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:41.164135", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:41.209633", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.210416", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.05", "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": "34031_1.5.4.21", "uuid": "34031_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:41.212138", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-07T05:40:41.234834", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/3/tests/5_ci-dumpjournal test_uuid 34031_1.5.4.21"}
- {"dt": "2023-12-07T05:40:41.236536", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.237281", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/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": "34031_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:41.238793", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-07T05:40:41.239450", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/0 for stage 0"}
- {"dt": "2023-12-07T05:40:41.240454", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-07T05:40:41.241396", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/1 for stage 1"}
- {"dt": "2023-12-07T05:40:41.242436", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-07T05:40:41.243165", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-07T05:40:41.243803", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-07T05:40:41.244646", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/2 for stage 2"}
- {"dt": "2023-12-07T05:40:41.245812", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-07T05:40:41.246680", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34031/lava-overlay-9vfmti3w/lava-34031/3 for stage 3"}
- {"dt": "2023-12-07T05:40:41.247722", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-07T05:40:41.248848", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-07T05:40:41.249818", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-07T05:40:41.931857", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:40:41.932834", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-07T05:40:41.934010", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.934791", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:40:41.935495", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:44) [common]"}
- {"dt": "2023-12-07T05:40:41.936161", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:44) [common]"}
- {"dt": "2023-12-07T05:40:41.936998", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.937625", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.938253", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:44) [common]"}
- {"dt": "2023-12-07T05:40:41.938783", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-07T05:40:41.939292", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61986, NBD-ROOT: 34031/nbd-deploy-u9cnoc0o/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:40:41.942698", "lvl": "debug", "msg": "nbd-server 61986 /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:40:41.964974", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-07T05:40:41.966715", "lvl": "debug", "msg": "output: ** (process:12424): WARNING **: 05:40:41.960: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-07T05:40:41.967249", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-07T05:40:41.967728", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-07T05:40:41.968629", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.969476", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:16) [common]"}
- {"dt": "2023-12-07T05:40:41.970225", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:40:41.970962", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:40:41.971587", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.972273", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:40:41.973575", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-07T05:40:41.974270", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:40:41.974793", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-07T05:40:41.975252", "lvl": "debug", "msg": "- {DTB}: 34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:40:41.975701", "lvl": "debug", "msg": "- {INITRD}: 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:40:41.976236", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-07T05:40:41.976716", "lvl": "debug", "msg": "- {KERNEL}: 34031/nbd-deploy-u9cnoc0o/kernel/Image"}
- {"dt": "2023-12-07T05:40:41.977180", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-07T05:40:41.977621", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-07T05:40:41.978051", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61986"}
- {"dt": "2023-12-07T05:40:41.978480", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-07T05:40:41.978910", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-07T05:40:41.979337", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-07T05:40:41.979762", "lvl": "debug", "msg": "- {RAMDISK}: 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:40:41.980231", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-07T05:40:41.980680", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-07T05:40:41.981127", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-07T05:40:41.981564", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-07T05:40:41.981994", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-07T05:40:41.982422", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-07T05:40:41.982861", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-07T05:40:41.983304", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:41.983740", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:41.984171", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-07T05:40:41.984656", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:40:41.985100", "lvl": "info", "msg": "- tftp 0x68080000 34031/nbd-deploy-u9cnoc0o/kernel/Image"}
- {"dt": "2023-12-07T05:40:41.985536", "lvl": "info", "msg": "- tftp 0x4a000000 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:40:41.985981", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:40:41.986416", "lvl": "info", "msg": "- tftp 0x68000000 34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:40:41.986849", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61986 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-07T05:40:41.987323", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:40:41.987942", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:40:41.988610", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:41.990039", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:40:41.990612", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-07T05:40:45.173827", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-07T05:40:45.175864", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-07T05:40:45.178484", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-07T05:40:45.179263", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-07T05:40:45.179940", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-07T05:40:45.184424", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-07T05:40:48.686111", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:40:49.754496", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:40:49.788535", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-07T05:40:49.890857", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:40:49.891748", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.71", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:40:49.893176", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:40:49.894036", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-07T05:40:49.894776", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-07T05:40:49.895359", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-07T05:40:49.897200", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-07T05:40:49.897942", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-07T05:40:49.898496", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-07T05:40:49.969673", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Ini\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:49.970479", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.013174", "lvl": "target", "msg": "NOTICE:  B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.013974", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.114250", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev\uFFFD\0\0NOTICE:  BL\uFFFD\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.159087", "lvl": "target", "msg": "NOTICE:  BL2: PRR is \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.159870", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.202334", "lvl": "target", "msg": "NOTICE:  BL2: Board i\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.203108", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.271554", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Prem\uFFFD\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.273079", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.320683", "lvl": "target", "msg": "NOTICE:  BL2: Board is\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.321637", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.323932", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:40:50.373576", "lvl": "target", "msg": "NOTICE:  BL2: Boot devi\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.374888", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.375899", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:40:50.377613", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:40:50.435192", "lvl": "target", "msg": "NOTICE:  \uFFFD\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.436301", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.437126", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:40:50.438728", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:40:50.503132", "lvl": "target", "msg": "\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:40:50.504222", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:40:50.505493", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:40:50.506419", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:40:50.507296", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-07T05:40:50.508070", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-07T05:40:50.508836", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-07T05:40:50.509672", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-07T05:40:50.548844", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-07T05:40:50.550630", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-07T05:40:50.551786", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-07T05:40:50.552895", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-07T05:40:50.553990", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:50.554996", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:50.555972", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:50.556640", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:50.557114", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-07T05:40:50.557741", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-07T05:40:50.558368", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-07T05:40:50.591677", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-07T05:40:50.592757", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-07T05:40:50.593493", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-07T05:40:50.594190", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-07T05:40:50.595513", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-07T05:40:50.596519", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-07T05:40:50.597284", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-07T05:40:50.632044", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-07T05:40:50.633287", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-07T05:40:50.635463", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-07T05:40:50.963801", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:40:50.964556", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:40:50.965028", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-07T05:40:50.965456", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:40:50.966241", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-07T05:40:50.967282", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-07T05:40:51.158541", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-07T05:40:51.187470", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-07T05:40:51.188633", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-07T05:40:51.189965", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:51.191339", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:51.192262", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:40:51.192982", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:40:51.222298", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-07T05:40:51.402028", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-07T05:40:51.403035", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-07T05:40:51.403816", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-07T05:40:51.405362", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-07T05:40:51.452279", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-07T05:40:51.468932", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-07T05:40:51.520357", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-07T05:40:51.522346", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-07T05:40:51.523773", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:50) [common]"}
- {"dt": "2023-12-07T05:40:51.524854", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-07T05:40:51.526044", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:50)"}
- {"dt": "2023-12-07T05:40:51.548853", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-07T05:40:51.550267", "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-07T05:40:51.551198", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-07T05:40:51.652807", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-07T05:40:51.654110", "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-07T05:40:51.666790", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-07T05:40:51.667800", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-07T05:40:51.769282", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:51.770875", "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-07T05:40:51.787727", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:51.789171", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-07T05:40:51.890891", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:51.892029", "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-07T05:40:51.900338", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:40:51.901881", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-07T05:40:52.003691", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-07T05:40:52.004815", "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-07T05:40:52.012159", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-07T05:40:55.254599", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2023-12-07T05:40:55.270212", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-07T05:40:55.509713", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-07T05:40:56.020793", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-07T05:40:56.292327", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1032 ms)"}
- {"dt": "2023-12-07T05:40:56.294176", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-07T05:40:56.396026", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:40:56.397216", "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-07T05:40:56.403940", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:40:56.405316", "lvl": "input", "msg": "tftp 0x68080000 34031/nbd-deploy-u9cnoc0o/kernel/Image\n"}
- {"dt": "2023-12-07T05:40:56.507221", "lvl": "target", "msg": "=> tftp 0x68080000 34031/nbd-deploy-u9cnoc0o/kernel/Image"}
- {"dt": "2023-12-07T05:40:56.508957", "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:45)"}
- {"dt": "2023-12-07T05:40:56.515758", "lvl": "target", "msg": "tftp 0x68080000 34031/nbd-deploy-u9cnoc0o/kernel/Image"}
- {"dt": "2023-12-07T05:40:56.551747", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:40:56.553235", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:40:56.555324", "lvl": "target", "msg": "Filename '34031/nbd-deploy-u9cnoc0o/kernel/Image'."}
- {"dt": "2023-12-07T05:40:56.556667", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-07T05:40:57.065789", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-07T05:40:57.592790", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:40:58.087220", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:40:58.629705", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:40:59.157440", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:40:59.684883", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:00.258909", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:00.786228", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:01.312959", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:01.825164", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:02.351159", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:02.863065", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:03.373068", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:03.885226", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:04.427176", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:04.907212", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:05.402452", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:05.912323", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:06.408533", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:06.934325", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:07.461380", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:07.972565", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:08.531430", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:09.091350", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:09.634262", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:10.144570", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:10.655548", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:11.198335", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:11.725474", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:12.268757", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:12.811607", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:13.355455", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:13.881415", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:14.360323", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:14.839104", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:15.335066", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:15.877186", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:16.453043", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:16.963941", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:17.490735", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:18.034231", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:18.082338", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-07T05:41:18.098426", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-07T05:41:18.099671", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:41:18.100635", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-07T05:41:18.101839", "lvl": "input", "msg": "tftp 0x4a000000 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-07T05:41:18.203505", "lvl": "target", "msg": "=> tftp 0x4a000000 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:41:18.205777", "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:24)"}
- {"dt": "2023-12-07T05:41:18.241760", "lvl": "target", "msg": "tftp 0x4a000000 34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:41:18.242584", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:41:18.243669", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:41:18.244413", "lvl": "target", "msg": "Filename '34031/nbd-deploy-u9cnoc0o/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-07T05:41:18.245562", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-07T05:41:18.788044", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-07T05:41:19.331379", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:41:19.440161", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-07T05:41:19.441956", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-07T05:41:19.443417", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:41:19.445207", "lvl": "target", "msg": "Bytes transferred = 2062759 (1f79a7 hex)"}
- {"dt": "2023-12-07T05:41:19.448173", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-07T05:41:19.550897", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:41:19.553042", "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-07T05:41:19.569867", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:41:19.570983", "lvl": "input", "msg": "tftp 0x68000000 34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-07T05:41:19.672770", "lvl": "target", "msg": "=> tftp 0x68000000 34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:41:19.674613", "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-07T05:41:19.679402", "lvl": "target", "msg": "tftp 0x68000000 34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:41:19.710659", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:41:19.711938", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:41:19.712810", "lvl": "target", "msg": "Filename '34031/nbd-deploy-u9cnoc0o/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-07T05:41:19.713513", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-07T05:41:19.768492", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-07T05:41:19.770231", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-07T05:41:19.771720", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:41:19.773311", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-07T05:41:19.775178", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61986 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-07T05:41:19.876694", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61986 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-07T05:41:19.878876", "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-07T05:41:19.896649", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61986 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-07T05:41:19.897702", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-07T05:41:19.999330", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:41:20.001463", "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-07T05:41:20.003766", "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:22)"}
- {"dt": "2023-12-07T05:41:20.008026", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:41:20.024500", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-07T05:41:20.170396", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-07T05:41:20.171734", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-07T05:41:20.172946", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff9a7 ... OK"}
- {"dt": "2023-12-07T05:41:20.174604", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-07T05:41:20.175889", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-07T05:41:20.177074", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-07T05:41:20.178140", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-07T05:41:20.179182", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-07T05:41:20.180402", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-07T05:41:20.214891", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-07T05:41:20.215648", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-07T05:41:20.216295", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-07T05:41:20.217320", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-07T05:41:20.218146", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-07T05:41:20.219010", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-07T05:41:20.219848", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-07T05:41:20.220688", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-07T05:41:20.221457", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-07T05:41:20.222220", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-07T05:41:20.223138", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-07T05:41:20.236267", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-07T05:41:20.237207", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:41:20.238011", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-07T05:41:20.239623", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-07T05:41:20.240531", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:41:20.241834", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:41:20.242739", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:29) [common]"}
- {"dt": "2023-12-07T05:41:20.243574", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "28.72", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:41:20.245193", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:41:20.246080", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:35) [common]"}
- {"dt": "2023-12-07T05:41:20.247029", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "35.07", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:41:20.248620", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-07T05:41:20.249745", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:41:20.250615", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:38) [common]"}
- {"dt": "2023-12-07T05:41:20.251471", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "38.28", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:41:20.253123", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-07T05:41:20.253935", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/initrd"}
- {"dt": "2023-12-07T05:41:20.255823", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/kernel"}
- {"dt": "2023-12-07T05:41:20.265523", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/dtb"}
- {"dt": "2023-12-07T05:41:20.267002", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34031/nbd-deploy-u9cnoc0o/nbdroot"}
- {"dt": "2023-12-07T05:41:20.490644", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-07T05:41:20.491823", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-07T05:41:23.943408", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:41:23.979244", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-07T05:41:24.082844", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-07T05:41:24.085248", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.59", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:41:24.089596", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-07T05:41:24.093181", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-07T05:41:24.094120", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.094741", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.095273", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.095777", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.096313", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.096825", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.097312", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.097814", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.098306", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.098783", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.099275", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.099759", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.100277", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.100764", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.101246", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.101745", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.102233", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.102713", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.103188", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.103675", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.104154", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.104673", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.105158", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.105642", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.106127", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.106607", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.107087", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.107581", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.108059", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.108586", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.109073", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.109548", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.110020", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.110494", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.110965", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.111438", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.111905", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.112409", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.112885", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.113363", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.113837", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.114310", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.114780", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.115257", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.115725", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.116223", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.116707", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.117185", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.117655", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-07T05:41:24.118455", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-07T05:41:25.097551", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-07T05:41:25.099425", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-07T05:41:25.100778", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-07T05:41:25.202973", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:41:25.204915", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-07T05:41:25.206489", "lvl": "debug", "msg": "clean NBD port 61986"}
- {"dt": "2023-12-07T05:41:28.482903", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34031"}
- {"dt": "2023-12-07T05:41:28.574894", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34031"}
- {"dt": "2023-12-07T05:41:28.575953", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-07T05:41:28.576617", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
