- {"dt": "2023-11-24T14:06:02.201118", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-11-24T14:06:02.202536", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-11-24T14:06:02.203587", "lvl": "info", "msg": "Start time: 2023-11-24 14:06:02.203530+00:00 (UTC)"}
- {"dt": "2023-11-24T14:06:02.204814", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-11-24T14:06:02.425173", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-11-24T14:06:02.642654", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-11-24T14:06:02.853162", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-11-24T14:06:03.084524", "lvl": "info", "msg": "validate duration: 0.88"}
- {"dt": "2023-11-24T14:06:03.085329", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-11-24T14:06:03.086490", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T14:06:03.087154", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-11-24T14:06:03.087766", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-11-24T14:06:03.088393", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-11-24T14:06:03.089462", "lvl": "debug", "msg": "Set_port 61998"}
- {"dt": "2023-11-24T14:06:03.090366", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T14:06:03.091166", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T14:06:03.092437", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:06:03.093134", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:06:03.093747", "lvl": "debug", "msg": "total size: 2062671 (1MB)"}
- {"dt": "2023-11-24T14:06:03.094310", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-24T14:06:03.408533", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-11-24T14:06:03.615905", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-11-24T14:06:03.716508", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-11-24T14:06:03.724179", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-11-24T14:06:03.822370", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-11-24T14:06:03.827941", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-11-24T14:06:03.920801", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-11-24T14:06:03.933393", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-11-24T14:06:03.938918", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-11-24T14:06:03.943724", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-11-24T14:06:03.948360", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-11-24T14:06:03.953965", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-11-24T14:06:04.018053", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-11-24T14:06:04.026440", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-11-24T14:06:04.030805", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-11-24T14:06:04.121965", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-11-24T14:06:04.133344", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-11-24T14:06:04.137773", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-11-24T14:06:04.143311", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-11-24T14:06:04.146886", "lvl": "info", "msg": "1MB downloaded in 1.05s (1.87MB/s)"}
- {"dt": "2023-11-24T14:06:04.148141", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:06:04.148894", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.06", "extra": {"label": "initrd", "md5sum": "7024b1c1cc95aae5b9d121e4823d0b45", "sha256sum": "996ba547beda6a7f03db8330dc741f374b7ef65388da3522c3916096ab4b78f2", "sha512sum": "04cc917b3b1cfe68935a4b8920e73c836b42b0fc2e96baea5ade4bbe91ec91b24321d6900c1221bd27bb0a9f30c0ac032d2c12b8f3fa7d930c58da5d03ade326", "size": ! "2062671"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:06:04.150426", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:06:04.151205", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T14:06:04.151975", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T14:06:04.153124", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-11-24T14:06:04.153799", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/kernel/Image"}
- {"dt": "2023-11-24T14:06:04.154349", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-11-24T14:06:04.154885", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-24T14:06:04.456265", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-24T14:06:05.076357", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-11-24T14:06:05.192469", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-11-24T14:06:05.362946", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-11-24T14:06:05.646388", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-11-24T14:06:05.857723", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-11-24T14:06:06.141008", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-11-24T14:06:06.352631", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-11-24T14:06:06.622881", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-11-24T14:06:06.842435", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-11-24T14:06:07.050310", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-11-24T14:06:07.303999", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-11-24T14:06:07.524706", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-11-24T14:06:07.733678", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-11-24T14:06:07.939018", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-11-24T14:06:08.156806", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-11-24T14:06:08.400694", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-11-24T14:06:08.611798", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-11-24T14:06:08.815977", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-11-24T14:06:09.021328", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-11-24T14:06:09.222866", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-11-24T14:06:09.224443", "lvl": "info", "msg": "37MB downloaded in 5.07s (7.37MB/s)"}
- {"dt": "2023-11-24T14:06:09.225597", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T14:06:09.226276", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "5.07", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:06:09.227740", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T14:06:09.228576", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T14:06:09.229375", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T14:06:09.230463", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:06:09.231148", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:06:09.231721", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-11-24T14:06:09.232315", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-24T14:06:09.543472", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-11-24T14:06:09.645140", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-11-24T14:06:09.650814", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-11-24T14:06:09.654463", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-11-24T14:06:09.656638", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:06:09.657347", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.43", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:06:09.658801", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:06:09.659589", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T14:06:09.660410", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T14:06:09.661535", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29420/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-11-24T14:06:09.662217", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-24T14:06:09.662774", "lvl": "debug", "msg": "total size: 453552428 (432MB)"}
- {"dt": "2023-11-24T14:06:09.663313", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-11-24T14:06:09.987558", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-24T14:06:16.122414", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-11-24T14:06:20.344259", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-11-24T14:06:39.199620", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-11-24T14:06:49.255507", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-11-24T14:06:58.572796", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-11-24T14:07:04.239848", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-11-24T14:07:10.976618", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-11-24T14:07:18.972659", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-11-24T14:07:26.320866", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-11-24T14:07:32.428849", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-11-24T14:07:39.526359", "lvl": "debug", "msg": "progress  55% (237MB)"}
- {"dt": "2023-11-24T14:07:44.994025", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-11-24T14:07:50.861635", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-11-24T14:07:58.932470", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-11-24T14:08:06.331908", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-11-24T14:08:12.040022", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-11-24T14:08:17.095651", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-11-24T14:08:21.600800", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-11-24T14:08:26.101127", "lvl": "debug", "msg": "progress  95% (410MB)"}
- {"dt": "2023-11-24T14:08:33.958139", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-11-24T14:08:37.383343", "lvl": "info", "msg": "432MB downloaded in 147.72s (2.93MB/s)"}
- {"dt": "2023-11-24T14:08:37.385357", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:28) [common]"}
- {"dt": "2023-11-24T14:08:37.386192", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "147.73", "extra": {"label": "nbdroot", "md5sum": "b5e80e9b956f1c8f514220bb6d79611b", "sha256sum": "842b6d61e14a471fdf6827cdcc0c728f6d9a884a4245790f9a7bd4957f8f26fd", "sha512sum": "d1f87792c50f0600b3c7e62fea8325420c53edb51df57b6ed52a606541484f0e9c7d69363566766d8ebe5296c964da9577259baa73552df32b62b3ef12c6bb10", "size": ! "453552428"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:37.387879", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:28) [common]"}
- {"dt": "2023-11-24T14:08:37.388866", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.390364", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8"}
- {"dt": "2023-11-24T14:08:37.391589", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin"}
- {"dt": "2023-11-24T14:08:37.392666", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/tests"}
- {"dt": "2023-11-24T14:08:37.393667", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/results"}
- {"dt": "2023-11-24T14:08:37.394679", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-add-keys"}
- {"dt": "2023-11-24T14:08:37.396033", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-add-sources"}
- {"dt": "2023-11-24T14:08:37.397512", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-background-process-start"}
- {"dt": "2023-11-24T14:08:37.398866", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-background-process-stop"}
- {"dt": "2023-11-24T14:08:37.400168", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-common-functions"}
- {"dt": "2023-11-24T14:08:37.401630", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-echo-ipv4"}
- {"dt": "2023-11-24T14:08:37.402919", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-install-packages"}
- {"dt": "2023-11-24T14:08:37.404307", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-installed-packages"}
- {"dt": "2023-11-24T14:08:37.405662", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-os-build"}
- {"dt": "2023-11-24T14:08:37.406988", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-probe-channel"}
- {"dt": "2023-11-24T14:08:37.408409", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-probe-ip"}
- {"dt": "2023-11-24T14:08:37.409752", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-target-ip"}
- {"dt": "2023-11-24T14:08:37.411094", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-target-mac"}
- {"dt": "2023-11-24T14:08:37.412618", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-target-storage"}
- {"dt": "2023-11-24T14:08:37.414055", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-case"}
- {"dt": "2023-11-24T14:08:37.415470", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-event"}
- {"dt": "2023-11-24T14:08:37.416968", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-feedback"}
- {"dt": "2023-11-24T14:08:37.418451", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-raise"}
- {"dt": "2023-11-24T14:08:37.419910", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-reference"}
- {"dt": "2023-11-24T14:08:37.421474", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-runner"}
- {"dt": "2023-11-24T14:08:37.422922", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-set"}
- {"dt": "2023-11-24T14:08:37.424491", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-test-shell"}
- {"dt": "2023-11-24T14:08:37.426000", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-install-packages (oe)"}
- {"dt": "2023-11-24T14:08:37.427653", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/bin/lava-installed-packages (oe)"}
- {"dt": "2023-11-24T14:08:37.429154", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/environment"}
- {"dt": "2023-11-24T14:08:37.430342", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-11-24T14:08:37.431150", "lvl": "debug", "msg": "- LAVA_JOB_ID=33765"}
- {"dt": "2023-11-24T14:08:37.431873", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-11-24T14:08:37.433036", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.435487", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:37.436565", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.437286", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-11-24T14:08:37.438124", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:37.438976", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.439715", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-11-24T14:08:37.440655", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:37.441573", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.442393", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-11-24T14:08:37.443366", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:26) [common]"}
- {"dt": "2023-11-24T14:08:37.444169", "lvl": "debug", "msg": "Using /lava-33765 at stage 0"}
- {"dt": "2023-11-24T14:08:37.445330", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:37.446167", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/0/tests/0_network-veth'"}
- {"dt": "2023-11-24T14:08:38.009591", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-11-24T14:08:38.014229", "lvl": "debug", "msg": "uuid=33765_1.5.4.1 testdef=None"}
- {"dt": "2023-11-24T14:08:38.015361", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:38.016178", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.57", "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-11-24T14:08:38.017782", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:25) [common]"}
- {"dt": "2023-11-24T14:08:38.023057", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.023811", "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": "33765_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.025578", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:25) [common]"}
- {"dt": "2023-11-24T14:08:38.032429", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.033235", "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": "33765_1.5.4.1", "uuid": "33765_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.034934", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:25) [common]"}
- {"dt": "2023-11-24T14:08:38.041513", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/0/tests/0_network-veth test_uuid 33765_1.5.4.1"}
- {"dt": "2023-11-24T14:08:38.043189", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.043954", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.045818", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:25) [common]"}
- {"dt": "2023-11-24T14:08:38.046653", "lvl": "debug", "msg": "Using /lava-33765 at stage 1"}
- {"dt": "2023-11-24T14:08:38.049807", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:38.050715", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/1_ci-busybox'"}
- {"dt": "2023-11-24T14:08:38.628955", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/1_ci-busybox"}
- {"dt": "2023-11-24T14:08:38.639905", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-11-24T14:08:38.643376", "lvl": "debug", "msg": "uuid=33765_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-11-24T14:08:38.645017", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:38.645897", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "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-11-24T14:08:38.647444", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:38.654073", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.654844", "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": "33765_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.656552", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:38.664850", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.665610", "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": "33765_1.5.4.5", "uuid": "33765_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.667275", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:38.675745", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/1_ci-busybox test_uuid 33765_1.5.4.5"}
- {"dt": "2023-11-24T14:08:38.677493", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:38.678228", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:38.679890", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:38.680793", "lvl": "debug", "msg": "Using /lava-33765 at stage 1"}
- {"dt": "2023-11-24T14:08:38.681930", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:38.682761", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-11-24T14:08:39.256875", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-11-24T14:08:39.267856", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-11-24T14:08:39.270956", "lvl": "debug", "msg": "uuid=33765_1.5.4.9 testdef=None"}
- {"dt": "2023-11-24T14:08:39.272100", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:39.273110", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "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-11-24T14:08:39.274723", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:39.280404", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.281140", "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": "33765_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.282806", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:39.290679", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.291415", "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": "33765_1.5.4.9", "uuid": "33765_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.293108", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:39.300486", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/2_ci-smoke-tests-basic test_uuid 33765_1.5.4.9"}
- {"dt": "2023-11-24T14:08:39.303334", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.304092", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.305812", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:24) [common]"}
- {"dt": "2023-11-24T14:08:39.306554", "lvl": "debug", "msg": "Using /lava-33765 at stage 1"}
- {"dt": "2023-11-24T14:08:39.307740", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:39.308731", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/3_ci-service-check'"}
- {"dt": "2023-11-24T14:08:39.871670", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/3_ci-service-check"}
- {"dt": "2023-11-24T14:08:39.882856", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-11-24T14:08:39.886772", "lvl": "debug", "msg": "uuid=33765_1.5.4.13 testdef=None"}
- {"dt": "2023-11-24T14:08:39.887891", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:39.888750", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "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-11-24T14:08:39.890375", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:39.896146", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.896981", "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": "33765_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.898677", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:39.906425", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.907228", "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": "33765_1.5.4.13", "uuid": "33765_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.909526", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:39.917120", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1/tests/3_ci-service-check test_uuid 33765_1.5.4.13"}
- {"dt": "2023-11-24T14:08:39.917939", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-11-24T14:08:39.918617", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-11-24T14:08:39.920092", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:39.920910", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:39.922673", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:39.923514", "lvl": "debug", "msg": "Using /lava-33765 at stage 2"}
- {"dt": "2023-11-24T14:08:39.924681", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:39.925532", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/2/tests/4_screenshooter'"}
- {"dt": "2023-11-24T14:08:40.491225", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-11-24T14:08:40.495169", "lvl": "debug", "msg": "uuid=33765_1.5.4.17 testdef=None"}
- {"dt": "2023-11-24T14:08:40.496432", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:40.497268", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.57", "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-11-24T14:08:40.498807", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:40.504598", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:40.505465", "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": "33765_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:40.507254", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:40.513836", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:40.514671", "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": "33765_1.5.4.17", "uuid": "33765_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:40.516791", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:40.523001", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/2/tests/4_screenshooter test_uuid 33765_1.5.4.17"}
- {"dt": "2023-11-24T14:08:40.524804", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:40.525586", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:40.527248", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:23) [common]"}
- {"dt": "2023-11-24T14:08:40.528077", "lvl": "debug", "msg": "Using /lava-33765 at stage 3"}
- {"dt": "2023-11-24T14:08:40.529279", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T14:08:40.530056", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-11-24T14:08:41.098686", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-11-24T14:08:41.115504", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-11-24T14:08:41.120715", "lvl": "debug", "msg": "uuid=33765_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-11-24T14:08:41.122067", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:41.123487", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:41.126271", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-11-24T14:08:41.135569", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:41.136870", "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": "33765_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:41.138712", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-11-24T14:08:41.183365", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:41.184116", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.04", "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": "33765_1.5.4.21", "uuid": "33765_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:41.185828", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-11-24T14:08:41.213328", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/3/tests/5_ci-dumpjournal test_uuid 33765_1.5.4.21"}
- {"dt": "2023-11-24T14:08:41.215019", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:41.215772", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/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": "33765_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:41.217352", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-11-24T14:08:41.218043", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/0 for stage 0"}
- {"dt": "2023-11-24T14:08:41.219228", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-11-24T14:08:41.220930", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/1 for stage 1"}
- {"dt": "2023-11-24T14:08:41.222106", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-11-24T14:08:41.222907", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-11-24T14:08:41.223575", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-11-24T14:08:41.224543", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/2 for stage 2"}
- {"dt": "2023-11-24T14:08:41.225732", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-11-24T14:08:41.226658", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33765/lava-overlay-ktocs2l8/lava-33765/3 for stage 3"}
- {"dt": "2023-11-24T14:08:41.228666", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-11-24T14:08:41.229840", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-11-24T14:08:41.230798", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-11-24T14:08:42.023430", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:08:42.024783", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-11-24T14:08:42.026111", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.027059", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T14:08:42.027910", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:21) [common]"}
- {"dt": "2023-11-24T14:08:42.028925", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:21) [common]"}
- {"dt": "2023-11-24T14:08:42.029783", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.030549", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.031513", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:21) [common]"}
- {"dt": "2023-11-24T14:08:42.032996", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-11-24T14:08:42.033754", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61998, NBD-ROOT: 33765/nbd-deploy-gd4hd7t4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-24T14:08:42.037447", "lvl": "debug", "msg": "nbd-server 61998 /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-24T14:08:42.058404", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-24T14:08:42.060651", "lvl": "debug", "msg": "output: ** (process:13124): WARNING **: 14:08:42.055: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-11-24T14:08:42.061468", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-24T14:08:42.062064", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-11-24T14:08:42.062994", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.063813", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:39) [common]"}
- {"dt": "2023-11-24T14:08:42.066852", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T14:08:42.067778", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T14:08:42.068640", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.069568", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T14:08:42.070824", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-11-24T14:08:42.071447", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-24T14:08:42.071984", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-11-24T14:08:42.075109", "lvl": "debug", "msg": "- {DTB}: 33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:08:42.075676", "lvl": "debug", "msg": "- {INITRD}: 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:08:42.076171", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-11-24T14:08:42.076835", "lvl": "debug", "msg": "- {KERNEL}: 33765/nbd-deploy-gd4hd7t4/kernel/Image"}
- {"dt": "2023-11-24T14:08:42.077363", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-11-24T14:08:42.077840", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-11-24T14:08:42.078299", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61998"}
- {"dt": "2023-11-24T14:08:42.078740", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-11-24T14:08:42.079170", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-11-24T14:08:42.079599", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-11-24T14:08:42.080022", "lvl": "debug", "msg": "- {RAMDISK}: 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:08:42.084631", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-11-24T14:08:42.085328", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-11-24T14:08:42.085837", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-11-24T14:08:42.086303", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-11-24T14:08:42.086745", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-11-24T14:08:42.087178", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-11-24T14:08:42.087604", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-11-24T14:08:42.088050", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:42.091235", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:42.091779", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-11-24T14:08:42.092301", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-11-24T14:08:42.092978", "lvl": "info", "msg": "- tftp 0x68080000 33765/nbd-deploy-gd4hd7t4/kernel/Image"}
- {"dt": "2023-11-24T14:08:42.093555", "lvl": "info", "msg": "- tftp 0x4a000000 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:08:42.094083", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-11-24T14:08:42.094546", "lvl": "info", "msg": "- tftp 0x68000000 33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:08:42.094986", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61998 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-11-24T14:08:42.095471", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-24T14:08:42.096088", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T14:08:42.099905", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.03", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:42.101529", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T14:08:42.102172", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-11-24T14:08:45.405703", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-11-24T14:08:45.407960", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-11-24T14:08:45.414097", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-11-24T14:08:45.414955", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-11-24T14:08:45.415789", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-11-24T14:08:45.419230", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-11-24T14:08:48.893673", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-24T14:08:49.964584", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-24T14:08:50.005230", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-11-24T14:08:50.107603", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T14:08:50.109028", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.69", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:08:50.110726", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T14:08:50.111712", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-11-24T14:08:50.112626", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-11-24T14:08:50.113350", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-11-24T14:08:50.115253", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-11-24T14:08:50.116019", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-11-24T14:08:50.117357", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-11-24T14:08:50.172699", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Init\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.173472", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.215332", "lvl": "target", "msg": "NOTICE:  BL2: \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.216246", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.298367", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Lo\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.299172", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.340855", "lvl": "target", "msg": "NOTICE:  B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.341664", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.406029", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter K\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.406848", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.450944", "lvl": "target", "msg": "NOTICE:  BL2: Board \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.451767", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.460514", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-24T14:08:50.502491", "lvl": "target", "msg": "NOTICE:  BL2: \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.503343", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.503990", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-24T14:08:50.557761", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80M\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.558557", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.559152", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-24T14:08:50.560990", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-24T14:08:50.561710", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-24T14:08:50.622060", "lvl": "target", "msg": "NOT\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.622811", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.623378", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-24T14:08:50.623948", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-24T14:08:50.624644", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-24T14:08:50.625737", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-24T14:08:50.626410", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-11-24T14:08:50.690223", "lvl": "target", "msg": "\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-24T14:08:50.691019", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-24T14:08:50.691652", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-24T14:08:50.692308", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-24T14:08:50.692948", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-24T14:08:50.693570", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-24T14:08:50.694116", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-11-24T14:08:50.695103", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-11-24T14:08:50.736721", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-11-24T14:08:50.737490", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-11-24T14:08:50.739079", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-11-24T14:08:50.739732", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-11-24T14:08:50.740523", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:50.741105", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:50.741720", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:50.742349", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:50.744501", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-11-24T14:08:50.745315", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-11-24T14:08:50.746419", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-11-24T14:08:50.780322", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-11-24T14:08:50.781122", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-11-24T14:08:50.781835", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-11-24T14:08:50.782509", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-11-24T14:08:50.784093", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-11-24T14:08:50.784893", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-11-24T14:08:50.785851", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-11-24T14:08:50.818531", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-11-24T14:08:50.819345", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-11-24T14:08:50.821864", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-11-24T14:08:51.150901", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:08:51.151800", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:08:51.152470", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-11-24T14:08:51.153042", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:08:51.153597", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-11-24T14:08:51.155692", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-11-24T14:08:51.345562", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-11-24T14:08:51.374442", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-11-24T14:08:51.375226", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-11-24T14:08:51.375771", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:51.377766", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:51.378489", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-11-24T14:08:51.379020", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:08:51.409514", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-11-24T14:08:51.588939", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-11-24T14:08:51.589724", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-11-24T14:08:51.590307", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-11-24T14:08:51.592248", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-11-24T14:08:51.640136", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-11-24T14:08:51.657177", "lvl": "input", "msg": "\n"}
- {"dt": "2023-11-24T14:08:51.708359", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-11-24T14:08:51.709699", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-11-24T14:08:51.710713", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:50) [common]"}
- {"dt": "2023-11-24T14:08:51.711518", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-11-24T14:08:51.712354", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:50)"}
- {"dt": "2023-11-24T14:08:51.735038", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-11-24T14:08:51.736567", "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-11-24T14:08:51.737348", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-11-24T14:08:51.840334", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-11-24T14:08:51.841736", "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-11-24T14:08:51.849859", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-11-24T14:08:51.851112", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-11-24T14:08:51.953489", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:51.954977", "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-11-24T14:08:51.960058", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:51.961500", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-11-24T14:08:52.063479", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:52.064763", "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-11-24T14:08:52.071314", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-24T14:08:52.072598", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-11-24T14:08:52.177052", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-11-24T14:08:52.177948", "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-11-24T14:08:52.184578", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-11-24T14:08:55.272060", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-11-24T14:08:55.287555", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-11-24T14:08:55.527002", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-11-24T14:08:56.038223", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-11-24T14:08:56.309684", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1031 ms)"}
- {"dt": "2023-11-24T14:08:56.310932", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-11-24T14:08:56.412502", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-11-24T14:08:56.413453", "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-11-24T14:08:56.421461", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-11-24T14:08:56.422712", "lvl": "input", "msg": "tftp 0x68080000 33765/nbd-deploy-gd4hd7t4/kernel/Image\n"}
- {"dt": "2023-11-24T14:08:56.524340", "lvl": "target", "msg": "=> tftp 0x68080000 33765/nbd-deploy-gd4hd7t4/kernel/Image"}
- {"dt": "2023-11-24T14:08:56.525300", "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-11-24T14:08:56.533255", "lvl": "target", "msg": "tftp 0x68080000 33765/nbd-deploy-gd4hd7t4/kernel/Image"}
- {"dt": "2023-11-24T14:08:56.557111", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-24T14:08:56.557903", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-24T14:08:56.560504", "lvl": "target", "msg": "Filename '33765/nbd-deploy-gd4hd7t4/kernel/Image'."}
- {"dt": "2023-11-24T14:08:56.561280", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-11-24T14:08:57.039411", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-24T14:08:57.502448", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:08:57.997523", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:08:58.475598", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:08:58.939639", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:08:59.418702", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:08:59.880806", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:00.360834", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:00.854921", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:01.366917", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:01.846070", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:02.341191", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:02.835498", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:03.331413", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:03.810390", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:04.289441", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:04.784938", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:05.263457", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:05.949394", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:07.006500", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:07.754654", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:08.314456", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:08.824866", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:09.321559", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:09.817494", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:10.295980", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:10.887382", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:11.415207", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:11.926947", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:12.438745", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:12.966981", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:13.494534", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:14.022122", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:14.853453", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:16.260520", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:17.123663", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:17.666783", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:18.179091", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:18.737629", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:19.250222", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:19.793003", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:19.852597", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-11-24T14:09:19.853421", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-11-24T14:09:19.854045", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-24T14:09:19.855857", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-11-24T14:09:19.857374", "lvl": "input", "msg": "tftp 0x4a000000 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-11-24T14:09:19.959402", "lvl": "target", "msg": "=> tftp 0x4a000000 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:09:19.960780", "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-11-24T14:09:19.966487", "lvl": "target", "msg": "tftp 0x4a000000 33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-24T14:09:19.996639", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-24T14:09:19.997441", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-24T14:09:19.998113", "lvl": "target", "msg": "Filename '33765/nbd-deploy-gd4hd7t4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-11-24T14:09:20.000009", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-11-24T14:09:20.590615", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-24T14:09:21.149661", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-24T14:09:21.240587", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-11-24T14:09:21.242144", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-11-24T14:09:21.243697", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-24T14:09:21.245758", "lvl": "target", "msg": "Bytes transferred = 2062671 (1f794f hex)"}
- {"dt": "2023-11-24T14:09:21.248369", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-11-24T14:09:21.350736", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-11-24T14:09:21.352106", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-11-24T14:09:21.370293", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-11-24T14:09:21.371984", "lvl": "input", "msg": "tftp 0x68000000 33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-11-24T14:09:21.474162", "lvl": "target", "msg": "=> tftp 0x68000000 33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:09:21.476321", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:21)"}
- {"dt": "2023-11-24T14:09:21.481199", "lvl": "target", "msg": "tftp 0x68000000 33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-24T14:09:21.513215", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-24T14:09:21.514091", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-24T14:09:21.514969", "lvl": "target", "msg": "Filename '33765/nbd-deploy-gd4hd7t4/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-11-24T14:09:21.515809", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-11-24T14:09:21.573379", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-11-24T14:09:21.574993", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-11-24T14:09:21.576529", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-24T14:09:21.577747", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-11-24T14:09:21.579450", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61998 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-11-24T14:09:21.682007", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61998 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-11-24T14:09:21.684517", "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:20)"}
- {"dt": "2023-11-24T14:09:21.701742", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61998 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-11-24T14:09:21.703491", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-11-24T14:09:21.806140", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-24T14:09:21.808117", "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-11-24T14:09:21.810524", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:20)"}
- {"dt": "2023-11-24T14:09:21.814193", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-24T14:09:21.830094", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-11-24T14:09:21.976060", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-11-24T14:09:21.977546", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-11-24T14:09:21.979027", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff94f ... OK"}
- {"dt": "2023-11-24T14:09:21.980064", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-11-24T14:09:21.981335", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-11-24T14:09:21.982452", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-11-24T14:09:21.983484", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-11-24T14:09:21.985201", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-11-24T14:09:21.986749", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-11-24T14:09:22.020765", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-11-24T14:09:22.021748", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-11-24T14:09:22.022605", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-11-24T14:09:22.023417", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-11-24T14:09:22.024364", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-11-24T14:09:22.025351", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-11-24T14:09:22.026269", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-11-24T14:09:22.028017", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-11-24T14:09:22.029128", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-11-24T14:09:22.030087", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-11-24T14:09:22.031006", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-11-24T14:09:22.041967", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-11-24T14:09:22.043053", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:09:22.044940", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-11-24T14:09:22.045806", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-11-24T14:09:22.046641", "lvl": "target", "msg": ""}
- {"dt": "2023-11-24T14:09:22.048333", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-24T14:09:22.049304", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:30) [common]"}
- {"dt": "2023-11-24T14:09:22.050136", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "30.34", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-24T14:09:22.051682", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-24T14:09:22.052603", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:37) [common]"}
- {"dt": "2023-11-24T14:09:22.053559", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "36.64", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-24T14:09:22.055035", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-11-24T14:09:22.056052", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-24T14:09:22.056926", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:40) [common]"}
- {"dt": "2023-11-24T14:09:22.057656", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "39.99", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-24T14:09:22.059014", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-11-24T14:09:22.059813", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/initrd"}
- {"dt": "2023-11-24T14:09:22.061776", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/kernel"}
- {"dt": "2023-11-24T14:09:22.071331", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/dtb"}
- {"dt": "2023-11-24T14:09:22.072900", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33765/nbd-deploy-gd4hd7t4/nbdroot"}
- {"dt": "2023-11-24T14:09:22.236236", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-11-24T14:09:22.237501", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-11-24T14:09:25.846711", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-24T14:09:25.882402", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-11-24T14:09:25.985972", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-11-24T14:09:25.988095", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.75", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T14:09:25.992177", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-11-24T14:09:25.996335", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-24T14:09:25.997358", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-11-24T14:09:25.998102", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-11-24T14:09:25.998757", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-11-24T14:09:25.999400", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.000014", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.000688", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.001316", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.001937", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.002534", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.003120", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.003697", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.004332", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.004964", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.005562", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.006147", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.006731", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.007309", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.007886", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.008519", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.009119", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.009706", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.010281", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.010864", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.011454", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.012036", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.012689", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.013305", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.013895", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.014475", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.015050", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.015628", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.016250", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.016844", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.017417", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.018010", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.018594", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.019165", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.019724", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.020345", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.020962", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.021553", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.022149", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.022739", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.023316", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.023882", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.024504", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.025089", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.025675", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.026249", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-11-24T14:09:26.027168", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-24T14:09:26.999584", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-11-24T14:09:27.000472", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-11-24T14:09:27.001369", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-11-24T14:09:27.103782", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T14:09:27.105814", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-11-24T14:09:27.107525", "lvl": "debug", "msg": "clean NBD port 61998"}
- {"dt": "2023-11-24T14:09:31.132509", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33765"}
- {"dt": "2023-11-24T14:09:31.228589", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33765"}
- {"dt": "2023-11-24T14:09:31.229745", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-11-24T14:09:31.230484", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
