- {"dt": "2023-12-04T20:19:08.714276", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-04T20:19:08.715574", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-04T20:19:08.716683", "lvl": "info", "msg": "Start time: 2023-12-04 20:19:08.716622+00:00 (UTC)"}
- {"dt": "2023-12-04T20:19:08.717840", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-04T20:19:08.941217", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-04T20:19:09.149597", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-04T20:19:09.363739", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-04T20:19:09.590950", "lvl": "info", "msg": "validate duration: 0.87"}
- {"dt": "2023-12-04T20:19:09.591898", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-04T20:19:09.593391", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-04T20:19:09.594211", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-04T20:19:09.594968", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-04T20:19:09.595627", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-04T20:19:09.596824", "lvl": "debug", "msg": "Set_port 61988"}
- {"dt": "2023-12-04T20:19:09.597820", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-04T20:19:09.598759", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-04T20:19:09.600167", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:19:09.601043", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:19:09.601787", "lvl": "debug", "msg": "total size: 2062710 (1MB)"}
- {"dt": "2023-12-04T20:19:09.602426", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-04T20:19:09.905041", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-04T20:19:10.101429", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-04T20:19:10.198376", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-04T20:19:10.204614", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-04T20:19:10.296128", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-04T20:19:10.300888", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-04T20:19:10.307069", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-04T20:19:10.311766", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-04T20:19:10.393225", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-04T20:19:10.398444", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-04T20:19:10.403672", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-04T20:19:10.409754", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-04T20:19:10.414446", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-04T20:19:10.420290", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-04T20:19:10.424944", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-04T20:19:10.429657", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-04T20:19:10.492682", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-04T20:19:10.497727", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-04T20:19:10.503659", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-04T20:19:10.507360", "lvl": "info", "msg": "1MB downloaded in 0.91s (2.17MB/s)"}
- {"dt": "2023-12-04T20:19:10.508773", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:19:10.509560", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.91", "extra": {"label": "initrd", "md5sum": "7ccabf8e0e921306cee0aebf760bafe7", "sha256sum": "e6fd0509cdc6102d551634660f9aea972d4e48af429bfba484d1ad3acbdc7f9b", "sha512sum": "dbedae4e74483fdc3fc47320c3b8f5a8dbf587e1c5ef7d0ab3d16121b7535976f05389008955fcc8de85a2acd34d47d8e628b41bc020043d0dbd21b0bd9c36b9", "size": ! "2062710"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:19:10.511192", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:19:10.512022", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-04T20:19:10.512961", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-04T20:19:10.514180", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-04T20:19:10.514925", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/kernel/Image"}
- {"dt": "2023-12-04T20:19:10.515557", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-04T20:19:10.516140", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-04T20:19:13.477115", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-04T20:19:14.099681", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-04T20:19:14.301543", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-04T20:19:14.506925", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-04T20:19:14.766504", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-04T20:19:14.971530", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-04T20:19:15.164998", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-04T20:19:15.311306", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-04T20:19:15.510741", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-04T20:19:15.705617", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-04T20:19:15.889018", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-04T20:19:16.070719", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-04T20:19:16.207179", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-04T20:19:16.387554", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-04T20:19:16.518237", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-04T20:19:16.694212", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-04T20:19:16.820993", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-04T20:19:16.988269", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-04T20:19:17.111791", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-04T20:19:17.273499", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-04T20:19:17.396163", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-04T20:19:17.397745", "lvl": "info", "msg": "37MB downloaded in 6.88s (5.43MB/s)"}
- {"dt": "2023-12-04T20:19:17.398912", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:07) [common]"}
- {"dt": "2023-12-04T20:19:17.399634", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "6.89", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:19:17.401252", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:07) [common]"}
- {"dt": "2023-12-04T20:19:17.402053", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-12-04T20:19:17.402862", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-12-04T20:19:17.404013", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:19:17.405125", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:19:17.405743", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-04T20:19:17.406362", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-04T20:19:17.706881", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-04T20:19:17.803996", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-04T20:19:17.806532", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-04T20:19:17.808113", "lvl": "info", "msg": "0MB downloaded in 0.40s (0.20MB/s)"}
- {"dt": "2023-12-04T20:19:17.809410", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:19:17.810154", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.41", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:19:17.811698", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:19:17.812588", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-12-04T20:19:17.813581", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-12-04T20:19:17.814768", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29500/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-04T20:19:17.815485", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-04T20:19:17.816063", "lvl": "debug", "msg": "total size: 453836808 (432MB)"}
- {"dt": "2023-12-04T20:19:17.816775", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-04T20:19:18.134121", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-04T20:19:24.600106", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-04T20:19:29.183467", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-04T20:19:34.624037", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-04T20:19:38.957276", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-04T20:19:44.092839", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-04T20:19:48.953476", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-04T20:19:54.729637", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-04T20:20:01.516315", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-04T20:20:08.045838", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-04T20:20:13.327898", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-04T20:20:19.238541", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-04T20:20:24.445284", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-04T20:20:29.117436", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-04T20:20:34.123308", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-04T20:20:38.890907", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-04T20:20:43.152165", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-04T20:20:47.516425", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-04T20:20:51.863537", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-04T20:20:57.286915", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-04T20:21:06.970039", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-04T20:21:10.072824", "lvl": "info", "msg": "432MB downloaded in 112.26s (3.86MB/s)"}
- {"dt": "2023-12-04T20:21:10.075698", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:52) [common]"}
- {"dt": "2023-12-04T20:21:10.076818", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "112.26", "extra": {"label": "nbdroot", "md5sum": "4f499af40a52457cfacfb2b500ab551f", "sha256sum": "9a8f69c00d4d7fa29b6333e52420a9b757579ffdc3fc2c9561f5771fe8b341b6", "sha512sum": "71c1f6a01b2f6a60a6a3053e9f030ac5d0e5a3915d170cdcb2c6d1711c68013f3c5509fa73a51ab709ca23b4dfb832419b8d470b92d2d4441705f0df7979e48c", "size": ! "453836808"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:10.078313", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:52) [common]"}
- {"dt": "2023-12-04T20:21:10.079006", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-12-04T20:21:10.080410", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4"}
- {"dt": "2023-12-04T20:21:10.081571", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin"}
- {"dt": "2023-12-04T20:21:10.082498", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/tests"}
- {"dt": "2023-12-04T20:21:10.083352", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/results"}
- {"dt": "2023-12-04T20:21:10.084356", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-add-keys"}
- {"dt": "2023-12-04T20:21:10.085674", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-add-sources"}
- {"dt": "2023-12-04T20:21:10.086900", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-background-process-start"}
- {"dt": "2023-12-04T20:21:10.088166", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-background-process-stop"}
- {"dt": "2023-12-04T20:21:10.089457", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-common-functions"}
- {"dt": "2023-12-04T20:21:10.090638", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-echo-ipv4"}
- {"dt": "2023-12-04T20:21:10.091815", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-install-packages"}
- {"dt": "2023-12-04T20:21:10.093144", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-installed-packages"}
- {"dt": "2023-12-04T20:21:10.095630", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-os-build"}
- {"dt": "2023-12-04T20:21:10.097250", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-probe-channel"}
- {"dt": "2023-12-04T20:21:10.099100", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-probe-ip"}
- {"dt": "2023-12-04T20:21:10.100658", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-target-ip"}
- {"dt": "2023-12-04T20:21:10.102078", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-target-mac"}
- {"dt": "2023-12-04T20:21:10.103451", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-target-storage"}
- {"dt": "2023-12-04T20:21:10.104922", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-case"}
- {"dt": "2023-12-04T20:21:10.106169", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-event"}
- {"dt": "2023-12-04T20:21:10.107385", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-feedback"}
- {"dt": "2023-12-04T20:21:10.108917", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-raise"}
- {"dt": "2023-12-04T20:21:10.110373", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-reference"}
- {"dt": "2023-12-04T20:21:10.111658", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-runner"}
- {"dt": "2023-12-04T20:21:10.112986", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-set"}
- {"dt": "2023-12-04T20:21:10.114633", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-test-shell"}
- {"dt": "2023-12-04T20:21:10.116532", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-04T20:21:10.118248", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-04T20:21:10.119721", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/environment"}
- {"dt": "2023-12-04T20:21:10.120910", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-04T20:21:10.121675", "lvl": "debug", "msg": "- LAVA_JOB_ID=33994"}
- {"dt": "2023-12-04T20:21:10.122191", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-04T20:21:10.123086", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.125399", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.126306", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.127096", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-04T20:21:10.127816", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.128550", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.129091", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-04T20:21:10.129720", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.130424", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.131070", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-04T20:21:10.131927", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.132725", "lvl": "debug", "msg": "Using /lava-33994 at stage 0"}
- {"dt": "2023-12-04T20:21:10.133614", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:10.134291", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/0/tests/0_network-veth'"}
- {"dt": "2023-12-04T20:21:10.781692", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-04T20:21:10.787001", "lvl": "debug", "msg": "uuid=33994_1.5.4.1 testdef=None"}
- {"dt": "2023-12-04T20:21:10.788334", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:10.789471", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:10.791640", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.798343", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.798995", "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": "33994_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:10.800454", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.806349", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.807057", "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": "33994_1.5.4.1", "uuid": "33994_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:10.808781", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.814717", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/0/tests/0_network-veth test_uuid 33994_1.5.4.1"}
- {"dt": "2023-12-04T20:21:10.816478", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:10.817343", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:10.818918", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-04T20:21:10.819567", "lvl": "debug", "msg": "Using /lava-33994 at stage 1"}
- {"dt": "2023-12-04T20:21:10.820552", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:10.821272", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-04T20:21:11.425221", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/1_ci-busybox"}
- {"dt": "2023-12-04T20:21:11.440575", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-04T20:21:11.443894", "lvl": "debug", "msg": "uuid=33994_1.5.4.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-04T20:21:11.445214", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:11.446043", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:11.447593", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-04T20:21:11.454271", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:11.455017", "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": "33994_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:11.456645", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-04T20:21:11.464320", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:11.465040", "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": "33994_1.5.4.5", "uuid": "33994_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:11.466579", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-04T20:21:11.474056", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/1_ci-busybox test_uuid 33994_1.5.4.5"}
- {"dt": "2023-12-04T20:21:11.475644", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:11.476415", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:11.477990", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:58) [common]"}
- {"dt": "2023-12-04T20:21:11.478708", "lvl": "debug", "msg": "Using /lava-33994 at stage 1"}
- {"dt": "2023-12-04T20:21:11.479732", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:11.480537", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-04T20:21:12.111834", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-04T20:21:12.126470", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-04T20:21:12.130216", "lvl": "debug", "msg": "uuid=33994_1.5.4.9 testdef=None"}
- {"dt": "2023-12-04T20:21:12.131240", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:12.131853", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.133277", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.138933", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.139600", "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": "33994_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.141059", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.147811", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.148558", "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": "33994_1.5.4.9", "uuid": "33994_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.150021", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.156504", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/2_ci-smoke-tests-basic test_uuid 33994_1.5.4.9"}
- {"dt": "2023-12-04T20:21:12.159117", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.159824", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.161337", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.161952", "lvl": "debug", "msg": "Using /lava-33994 at stage 1"}
- {"dt": "2023-12-04T20:21:12.162838", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:12.163476", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-04T20:21:12.799176", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/3_ci-service-check"}
- {"dt": "2023-12-04T20:21:12.815612", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-04T20:21:12.819515", "lvl": "debug", "msg": "uuid=33994_1.5.4.13 testdef=None"}
- {"dt": "2023-12-04T20:21:12.820584", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:12.821311", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.822820", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.828494", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.829217", "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": "33994_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.830742", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.837979", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.838744", "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": "33994_1.5.4.13", "uuid": "33994_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.840419", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.847350", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1/tests/3_ci-service-check test_uuid 33994_1.5.4.13"}
- {"dt": "2023-12-04T20:21:12.848085", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-04T20:21:12.848807", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-04T20:21:12.850237", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:12.850940", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:12.852554", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:57) [common]"}
- {"dt": "2023-12-04T20:21:12.853287", "lvl": "debug", "msg": "Using /lava-33994 at stage 2"}
- {"dt": "2023-12-04T20:21:12.854350", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:12.855112", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/2/tests/4_screenshooter'"}
- {"dt": "2023-12-04T20:21:13.504602", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-04T20:21:13.512100", "lvl": "debug", "msg": "uuid=33994_1.5.4.17 testdef=None"}
- {"dt": "2023-12-04T20:21:13.513740", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:13.514782", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:13.517100", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-04T20:21:13.522868", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:13.523540", "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": "33994_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:13.525109", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-04T20:21:13.531304", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:13.532019", "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": "33994_1.5.4.17", "uuid": "33994_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:13.533719", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-04T20:21:13.539697", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/2/tests/4_screenshooter test_uuid 33994_1.5.4.17"}
- {"dt": "2023-12-04T20:21:13.541457", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:13.542464", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:13.544134", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:56) [common]"}
- {"dt": "2023-12-04T20:21:13.544926", "lvl": "debug", "msg": "Using /lava-33994 at stage 3"}
- {"dt": "2023-12-04T20:21:13.545954", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-04T20:21:13.546763", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-04T20:21:14.183702", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-04T20:21:14.199797", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-04T20:21:14.203001", "lvl": "debug", "msg": "uuid=33994_1.5.4.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-04T20:21:14.204083", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:14.205225", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:14.206746", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.212228", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.212926", "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": "33994_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:14.214436", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.221976", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.222681", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33994_1.5.4.21", "uuid": "33994_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:14.224295", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.231709", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/3/tests/5_ci-dumpjournal test_uuid 33994_1.5.4.21"}
- {"dt": "2023-12-04T20:21:14.233369", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.234083", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/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": "33994_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:14.235467", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-04T20:21:14.236082", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/0 for stage 0"}
- {"dt": "2023-12-04T20:21:14.237112", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-04T20:21:14.238020", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/1 for stage 1"}
- {"dt": "2023-12-04T20:21:14.239022", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-04T20:21:14.239703", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-04T20:21:14.240340", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-04T20:21:14.241101", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/2 for stage 2"}
- {"dt": "2023-12-04T20:21:14.242088", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-04T20:21:14.242903", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33994/lava-overlay-eqzt9ud4/lava-33994/3 for stage 3"}
- {"dt": "2023-12-04T20:21:14.243889", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-04T20:21:14.244992", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-04T20:21:14.245853", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.918841", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:21:14.919852", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.921170", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.922088", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-04T20:21:14.922913", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.923788", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.924735", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.925535", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.926312", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:55) [common]"}
- {"dt": "2023-12-04T20:21:14.927010", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-04T20:21:14.927667", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61988, NBD-ROOT: 33994/nbd-deploy-hc79d76z/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-04T20:21:14.931124", "lvl": "debug", "msg": "nbd-server 61988 /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-04T20:21:14.952154", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-04T20:21:14.956777", "lvl": "debug", "msg": "output: ** (process:19123): WARNING **: 20:21:14.950: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-04T20:21:14.958224", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-04T20:21:14.959480", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-04T20:21:14.962059", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.966105", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:05) [common]"}
- {"dt": "2023-12-04T20:21:14.967707", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-04T20:21:14.969213", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-04T20:21:14.970522", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.971812", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-04T20:21:14.974050", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-04T20:21:14.974955", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-04T20:21:14.975727", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-04T20:21:14.976897", "lvl": "debug", "msg": "- {DTB}: 33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:21:14.977777", "lvl": "debug", "msg": "- {INITRD}: 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:21:14.978760", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-04T20:21:14.979622", "lvl": "debug", "msg": "- {KERNEL}: 33994/nbd-deploy-hc79d76z/kernel/Image"}
- {"dt": "2023-12-04T20:21:14.980700", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-04T20:21:14.981576", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-04T20:21:14.982479", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61988"}
- {"dt": "2023-12-04T20:21:14.983304", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-04T20:21:14.983887", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-04T20:21:14.984403", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-04T20:21:14.984858", "lvl": "debug", "msg": "- {RAMDISK}: 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:21:14.985341", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-04T20:21:14.985774", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-04T20:21:14.986199", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-04T20:21:14.986621", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-04T20:21:14.987042", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-04T20:21:14.987504", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-04T20:21:14.987946", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-04T20:21:14.988442", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:14.988885", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:14.989493", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-04T20:21:14.989953", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-04T20:21:14.990382", "lvl": "info", "msg": "- tftp 0x68080000 33994/nbd-deploy-hc79d76z/kernel/Image"}
- {"dt": "2023-12-04T20:21:14.990806", "lvl": "info", "msg": "- tftp 0x4a000000 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:21:14.991496", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-04T20:21:14.992168", "lvl": "info", "msg": "- tftp 0x68000000 33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:21:14.992709", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61988 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-04T20:21:14.993216", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-04T20:21:14.993840", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-04T20:21:14.994416", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:21:14.995832", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-04T20:21:14.996494", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-04T20:21:18.131878", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-04T20:21:18.134038", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-04T20:21:18.134836", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-04T20:21:18.135533", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-04T20:21:18.136517", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-04T20:21:18.137761", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-04T20:21:21.599360", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-04T20:21:22.672236", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-04T20:21:22.720465", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-04T20:21:22.822910", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-04T20:21:22.824545", "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-12-04T20:21:22.827302", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-04T20:21:22.828970", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-04T20:21:22.830376", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-04T20:21:22.831577", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-04T20:21:22.833568", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-04T20:21:22.834260", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-04T20:21:22.834896", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-04T20:21:22.886383", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 In\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:22.887420", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:22.928916", "lvl": "target", "msg": "NOTI\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:22.930685", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.009248", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Pr\0NOTICE:  BL2: R-Car Gen3 Init\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.054387", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.055472", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.097444", "lvl": "target", "msg": "NOTICE:  BL2: Boa\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.098311", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.144993", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Ki\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.146404", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.148723", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.194262", "lvl": "target", "msg": "\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.196411", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.198482", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.244595", "lvl": "target", "msg": "NOTICE:  BL2: Boot \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.245313", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.245878", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.299041", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.300078", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.301120", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.302919", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-04T20:21:23.360087", "lvl": "target", "msg": "NOTICE:  BL2: LCM state i\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.362158", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.363977", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.366274", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-04T20:21:23.368104", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-04T20:21:23.369889", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-04T20:21:23.427310", "lvl": "target", "msg": "NOTICE:  BL2: \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-04T20:21:23.429124", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-04T20:21:23.430754", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-04T20:21:23.432576", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-04T20:21:23.434142", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-04T20:21:23.435594", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-04T20:21:23.437110", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-04T20:21:23.438508", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-04T20:21:23.472674", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-04T20:21:23.473975", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-04T20:21:23.475396", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-04T20:21:23.476288", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-04T20:21:23.477090", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:23.477930", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:23.478641", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:23.479309", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:23.479949", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-04T20:21:23.480627", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-04T20:21:23.481392", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-04T20:21:23.516429", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-04T20:21:23.518326", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-04T20:21:23.519640", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-04T20:21:23.520944", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-04T20:21:23.522159", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-04T20:21:23.523387", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-04T20:21:23.524932", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-04T20:21:23.555278", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-04T20:21:23.556459", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-04T20:21:23.559239", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-04T20:21:23.887540", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:21:23.889639", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:21:23.891387", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-04T20:21:23.893345", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:21:23.895861", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-04T20:21:23.897815", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-04T20:21:24.081530", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-04T20:21:24.112070", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-04T20:21:24.113052", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-04T20:21:24.114339", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:24.115807", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:24.116705", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-04T20:21:24.117473", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:21:24.159325", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-04T20:21:24.325793", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-04T20:21:24.326651", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-04T20:21:24.327305", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-04T20:21:24.329190", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-04T20:21:24.376160", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-04T20:21:24.393509", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-04T20:21:24.445837", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-04T20:21:24.447729", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-04T20:21:24.449143", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-12-04T20:21:24.450209", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-04T20:21:24.451327", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-12-04T20:21:24.471971", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-04T20:21:24.474811", "lvl": "debug", "msg": "Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-12-04T20:21:24.476701", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-04T20:21:24.579194", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-04T20:21:24.580633", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-04T20:21:24.600499", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-04T20:21:24.601565", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-04T20:21:24.703369", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:24.705661", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-04T20:21:24.712031", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:24.714470", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-04T20:21:24.815965", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:24.818120", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-04T20:21:24.823724", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-04T20:21:24.826293", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-04T20:21:24.929079", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-04T20:21:24.931006", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-12-04T20:21:24.935913", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-04T20:21:28.027245", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-04T20:21:28.029260", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-04T20:21:28.283468", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-04T20:21:28.794884", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-04T20:21:29.065907", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1035 ms)"}
- {"dt": "2023-12-04T20:21:29.067207", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-04T20:21:29.169868", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-04T20:21:29.171506", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-04T20:21:29.177954", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-04T20:21:29.179863", "lvl": "input", "msg": "tftp 0x68080000 33994/nbd-deploy-hc79d76z/kernel/Image\n"}
- {"dt": "2023-12-04T20:21:29.282607", "lvl": "target", "msg": "=> tftp 0x68080000 33994/nbd-deploy-hc79d76z/kernel/Image"}
- {"dt": "2023-12-04T20:21:29.284163", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-04T20:21:29.289448", "lvl": "target", "msg": "tftp 0x68080000 33994/nbd-deploy-hc79d76z/kernel/Image"}
- {"dt": "2023-12-04T20:21:29.314676", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-04T20:21:29.317119", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-04T20:21:29.318705", "lvl": "target", "msg": "Filename '33994/nbd-deploy-hc79d76z/kernel/Image'."}
- {"dt": "2023-12-04T20:21:29.320090", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-04T20:21:30.436354", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-04T20:21:31.139717", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:31.731086", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:32.594944", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:33.746026", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:36.320916", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:38.143985", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:39.726052", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:40.445879", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:45.130460", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:45.754850", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:46.600983", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:48.888281", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:50.327364", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:52.054733", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:52.837367", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:53.556657", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:54.516012", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:55.395094", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:56.546414", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:57.170198", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:57.762434", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:58.673157", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:59.393011", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:21:59.952128", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:00.575711", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:01.167624", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:01.743188", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:02.318697", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:02.894202", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:03.469173", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:04.013352", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:04.652966", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:05.212532", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:05.787761", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:06.347648", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:06.923121", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:07.482646", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:08.042236", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:08.601761", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:09.161238", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:09.221871", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-04T20:22:09.223150", "lvl": "target", "msg": "\t 959 KiB/s"}
- {"dt": "2023-12-04T20:22:09.224301", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-04T20:22:09.226118", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-04T20:22:09.228321", "lvl": "input", "msg": "tftp 0x4a000000 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-04T20:22:09.330554", "lvl": "target", "msg": "=> tftp 0x4a000000 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:22:09.331922", "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:06)"}
- {"dt": "2023-12-04T20:22:09.335875", "lvl": "target", "msg": "tftp 0x4a000000 33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-04T20:22:09.368664", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-04T20:22:09.370887", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-04T20:22:09.373086", "lvl": "target", "msg": "Filename '33994/nbd-deploy-hc79d76z/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-04T20:22:09.376930", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-04T20:22:09.962715", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-04T20:22:10.522220", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-04T20:22:10.618191", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-04T20:22:10.620129", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-04T20:22:10.622066", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-04T20:22:10.634093", "lvl": "target", "msg": "Bytes transferred = 2062710 (1f7976 hex)"}
- {"dt": "2023-12-04T20:22:10.635440", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-04T20:22:10.737239", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-04T20:22:10.738692", "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:04)"}
- {"dt": "2023-12-04T20:22:10.745961", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-04T20:22:10.748378", "lvl": "input", "msg": "tftp 0x68000000 33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-04T20:22:10.851066", "lvl": "target", "msg": "=> tftp 0x68000000 33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:22:10.853222", "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:04)"}
- {"dt": "2023-12-04T20:22:10.893151", "lvl": "target", "msg": "tftp 0x68000000 33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-04T20:22:10.894329", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-04T20:22:10.895543", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-04T20:22:10.896511", "lvl": "target", "msg": "Filename '33994/nbd-deploy-hc79d76z/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-04T20:22:10.897637", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-04T20:22:10.952588", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-04T20:22:10.954238", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-04T20:22:10.955732", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-04T20:22:10.957236", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-04T20:22:10.959242", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61988 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-12-04T20:22:11.061905", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61988 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-04T20:22:11.063830", "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:04)"}
- {"dt": "2023-12-04T20:22:11.082501", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61988 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-04T20:22:11.084331", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-04T20:22:11.186307", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-04T20:22:11.188352", "lvl": "debug", "msg": "Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-12-04T20:22:11.190569", "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:04)"}
- {"dt": "2023-12-04T20:22:11.194274", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-04T20:22:11.209955", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-04T20:22:11.356834", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-04T20:22:11.358695", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-04T20:22:11.361416", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff976 ... OK"}
- {"dt": "2023-12-04T20:22:11.362991", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-04T20:22:11.364631", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-04T20:22:11.366109", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-04T20:22:11.367504", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-04T20:22:11.368982", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-04T20:22:11.369692", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-04T20:22:11.401481", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-04T20:22:11.402463", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-04T20:22:11.403777", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-04T20:22:11.405510", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-04T20:22:11.406372", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-04T20:22:11.407069", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-04T20:22:11.407733", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-04T20:22:11.408459", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-04T20:22:11.409125", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-04T20:22:11.409783", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-04T20:22:11.410581", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-04T20:22:11.422933", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-04T20:22:11.424465", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:22:11.426532", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-04T20:22:11.427418", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-04T20:22:11.428241", "lvl": "target", "msg": ""}
- {"dt": "2023-12-04T20:22:11.429674", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-04T20:22:11.430539", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:47) [common]"}
- {"dt": "2023-12-04T20:22:11.431371", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "46.98", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-04T20:22:11.432899", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-04T20:22:11.433432", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:53) [common]"}
- {"dt": "2023-12-04T20:22:11.433999", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "53.30", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-04T20:22:11.434969", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-04T20:22:11.435578", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-04T20:22:11.436094", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:56) [common]"}
- {"dt": "2023-12-04T20:22:11.436673", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "56.47", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-04T20:22:11.437877", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-04T20:22:11.438568", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/initrd"}
- {"dt": "2023-12-04T20:22:11.440296", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/kernel"}
- {"dt": "2023-12-04T20:22:11.449081", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/dtb"}
- {"dt": "2023-12-04T20:22:11.450473", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33994/nbd-deploy-hc79d76z/nbdroot"}
- {"dt": "2023-12-04T20:22:11.950333", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-04T20:22:11.951511", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-04T20:22:15.384906", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-04T20:22:15.421164", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-04T20:22:15.525663", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-04T20:22:15.527935", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.58", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-04T20:22:15.532476", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-04T20:22:15.534137", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-04T20:22:15.535166", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.535898", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.536589", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.537221", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.537815", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.538400", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.539150", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.539789", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.540447", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.541047", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.541629", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.542307", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.542982", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.543606", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.544233", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.544850", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.545501", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.546092", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.546667", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.547281", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.547878", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.548508", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.549101", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.549699", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.550289", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.550873", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.551482", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.552064", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.552692", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.553282", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.553859", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.554473", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.555106", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.555692", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.556309", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.556901", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.557468", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.558026", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.558579", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.559141", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.559698", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.560297", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.560892", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.561460", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.562029", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.562597", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.563155", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.563788", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.564407", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-04T20:22:15.565343", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-04T20:22:16.539100", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-04T20:22:16.541506", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-04T20:22:16.543409", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-04T20:22:16.647022", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-04T20:22:16.648901", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-04T20:22:16.650230", "lvl": "debug", "msg": "clean NBD port 61988"}
- {"dt": "2023-12-04T20:22:19.902211", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33994"}
- {"dt": "2023-12-04T20:22:19.998402", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33994"}
- {"dt": "2023-12-04T20:22:19.999486", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-04T20:22:20.000104", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
