- {"dt": "2023-12-06T05:22:53.268829", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-06T05:22:53.270129", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-06T05:22:53.271059", "lvl": "info", "msg": "Start time: 2023-12-06 05:22:53.271007+00:00 (UTC)"}
- {"dt": "2023-12-06T05:22:53.272166", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-06T05:22:53.492124", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-06T05:22:53.702262", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-06T05:22:53.909624", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-06T05:22:54.131661", "lvl": "info", "msg": "validate duration: 0.86"}
- {"dt": "2023-12-06T05:22:54.132549", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-06T05:22:54.133733", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-06T05:22:54.134489", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-06T05:22:54.135116", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-06T05:22:54.135710", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-06T05:22:54.136786", "lvl": "debug", "msg": "Set_port 61984"}
- {"dt": "2023-12-06T05:22:54.137690", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-06T05:22:54.138544", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-06T05:22:54.139811", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:22:54.140564", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:22:54.141193", "lvl": "debug", "msg": "total size: 2061259 (1MB)"}
- {"dt": "2023-12-06T05:22:54.141759", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-06T05:22:54.458230", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-06T05:22:54.660787", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-06T05:22:54.761141", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-06T05:22:54.769758", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-06T05:22:54.862649", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-06T05:22:54.868581", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-06T05:22:54.876688", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-06T05:22:54.881182", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-06T05:22:54.963046", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-06T05:22:54.969950", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-06T05:22:54.975688", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-06T05:22:54.981538", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-06T05:22:54.986094", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-06T05:22:54.991831", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-06T05:22:54.996403", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-06T05:22:55.000897", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-06T05:22:55.006468", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-06T05:22:55.069821", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-06T05:22:55.076179", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-06T05:22:55.080341", "lvl": "info", "msg": "1MB downloaded in 0.94s (2.09MB/s)"}
- {"dt": "2023-12-06T05:22:55.081663", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:22:55.082403", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.94", "extra": {"label": "initrd", "md5sum": "c9201568ef1694cc3889346ec25821c1", "sha256sum": "5dcbe0c129fd8195398923c2738eb093a29c3573bfffa7c9161dcda376a62c77", "sha512sum": "4ea46b836c61dea2f596b480d7e06d007351fe5e779b7adf60694e178acf8718498fe7dcf2ed6cf85d404d5776d0b1f26b975930414fb2a15c3ea5f030878644", "size": ! "2061259"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:22:55.083924", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:22:55.085299", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-06T05:22:55.086103", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-06T05:22:55.087486", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-06T05:22:55.088170", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/kernel/Image"}
- {"dt": "2023-12-06T05:22:55.089349", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-06T05:22:55.089911", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-06T05:22:55.406105", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-06T05:22:56.019833", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-06T05:22:56.226947", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-06T05:22:56.300549", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-06T05:22:56.406587", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-06T05:22:56.554249", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-06T05:22:56.634619", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-06T05:22:56.852667", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-06T05:22:56.956739", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-06T05:22:57.065000", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-06T05:22:57.233987", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-06T05:22:57.384471", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-06T05:22:57.547074", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-06T05:22:57.718791", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-06T05:22:57.874687", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-06T05:22:58.027804", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-06T05:22:58.197081", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-06T05:22:58.330195", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-06T05:22:58.467462", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-06T05:22:58.628982", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-06T05:22:58.760086", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-06T05:22:58.761697", "lvl": "info", "msg": "37MB downloaded in 3.67s (10.18MB/s)"}
- {"dt": "2023-12-06T05:22:58.762879", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:04) [common]"}
- {"dt": "2023-12-06T05:22:58.763557", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.68", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:22:58.765056", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:04) [common]"}
- {"dt": "2023-12-06T05:22:58.765842", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:55) [common]"}
- {"dt": "2023-12-06T05:22:58.766668", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:55) [common]"}
- {"dt": "2023-12-06T05:22:58.767772", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:22:58.768508", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:22:58.769089", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-06T05:22:58.769642", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-06T05:22:59.083286", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-06T05:22:59.184377", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-06T05:22:59.186869", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-06T05:22:59.188447", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-12-06T05:22:59.189714", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:22:59.190908", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:22:59.192724", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:22:59.193617", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:55) [common]"}
- {"dt": "2023-12-06T05:22:59.194510", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:55) [common]"}
- {"dt": "2023-12-06T05:22:59.195678", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29505/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-06T05:22:59.196458", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-06T05:22:59.197133", "lvl": "debug", "msg": "total size: 453769352 (432MB)"}
- {"dt": "2023-12-06T05:22:59.197746", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-06T05:22:59.520900", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-06T05:23:06.508630", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-06T05:23:10.946971", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-06T05:23:15.989461", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-06T05:23:20.737990", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-06T05:23:33.850227", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-06T05:23:45.342469", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-06T05:23:51.456004", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-06T05:23:58.510051", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-06T05:24:04.701277", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-06T05:24:09.794079", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-06T05:24:15.547020", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-06T05:24:20.804906", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-06T05:24:25.502962", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-06T05:24:30.543247", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-12-06T05:24:35.062294", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-06T05:24:41.117574", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-06T05:24:45.837689", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-06T05:24:50.479453", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-06T05:24:54.825678", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-06T05:25:06.465037", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-06T05:25:10.794482", "lvl": "info", "msg": "432MB downloaded in 131.60s (3.29MB/s)"}
- {"dt": "2023-12-06T05:25:10.796451", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:12) [common]"}
- {"dt": "2023-12-06T05:25:10.797533", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "131.60", "extra": {"label": "nbdroot", "md5sum": "dc13e898b5ec25550d7f632a2cee7a8b", "sha256sum": "f001e82048c0baf72822f38c3c867dc12a1c95ea3eb7cf25b58d70d9fb3a9214", "sha512sum": "6666c6c3c6369061c4df535a2d0539ebc0a8938eac52e28562a813c2823792bb32f9e61cbb5285f7a10957b8f043a608e7f488b421dfa04212757b054dabd580", "size": ! "453769352"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:10.799913", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:12) [common]"}
- {"dt": "2023-12-06T05:25:10.801213", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.802891", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94"}
- {"dt": "2023-12-06T05:25:10.804324", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin"}
- {"dt": "2023-12-06T05:25:10.805595", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/tests"}
- {"dt": "2023-12-06T05:25:10.807721", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/results"}
- {"dt": "2023-12-06T05:25:10.808932", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-add-keys"}
- {"dt": "2023-12-06T05:25:10.810449", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-add-sources"}
- {"dt": "2023-12-06T05:25:10.811890", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-background-process-start"}
- {"dt": "2023-12-06T05:25:10.813445", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-background-process-stop"}
- {"dt": "2023-12-06T05:25:10.814885", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-common-functions"}
- {"dt": "2023-12-06T05:25:10.816344", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-echo-ipv4"}
- {"dt": "2023-12-06T05:25:10.817821", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-install-packages"}
- {"dt": "2023-12-06T05:25:10.819258", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-installed-packages"}
- {"dt": "2023-12-06T05:25:10.820785", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-os-build"}
- {"dt": "2023-12-06T05:25:10.822253", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-probe-channel"}
- {"dt": "2023-12-06T05:25:10.823693", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-probe-ip"}
- {"dt": "2023-12-06T05:25:10.825165", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-target-ip"}
- {"dt": "2023-12-06T05:25:10.826622", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-target-mac"}
- {"dt": "2023-12-06T05:25:10.828093", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-target-storage"}
- {"dt": "2023-12-06T05:25:10.829762", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-case"}
- {"dt": "2023-12-06T05:25:10.831249", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-event"}
- {"dt": "2023-12-06T05:25:10.833262", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-feedback"}
- {"dt": "2023-12-06T05:25:10.834746", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-raise"}
- {"dt": "2023-12-06T05:25:10.836349", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-reference"}
- {"dt": "2023-12-06T05:25:10.837836", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-runner"}
- {"dt": "2023-12-06T05:25:10.839204", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-set"}
- {"dt": "2023-12-06T05:25:10.841253", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-test-shell"}
- {"dt": "2023-12-06T05:25:10.842764", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-06T05:25:10.844532", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-06T05:25:10.845981", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/environment"}
- {"dt": "2023-12-06T05:25:10.847105", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-06T05:25:10.847880", "lvl": "debug", "msg": "- LAVA_JOB_ID=34011"}
- {"dt": "2023-12-06T05:25:10.848647", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-06T05:25:10.849811", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.852797", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:10.853752", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.854454", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-06T05:25:10.855157", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:10.855975", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.856904", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-06T05:25:10.857650", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:10.858497", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.859266", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-06T05:25:10.860250", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:10.861070", "lvl": "debug", "msg": "Using /lava-34011 at stage 0"}
- {"dt": "2023-12-06T05:25:10.862065", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:10.862818", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/0/tests/0_network-veth'"}
- {"dt": "2023-12-06T05:25:11.465161", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-06T05:25:11.470329", "lvl": "debug", "msg": "uuid=34011_1.5.4.1 testdef=None"}
- {"dt": "2023-12-06T05:25:11.471389", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:11.472074", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-06T05:25:11.473744", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:11.479628", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:11.480507", "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": "34011_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:11.482223", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:11.488760", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:11.489483", "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": "34011_1.5.4.1", "uuid": "34011_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:11.491203", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:11.498483", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/0/tests/0_network-veth test_uuid 34011_1.5.4.1"}
- {"dt": "2023-12-06T05:25:11.500046", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:11.500797", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:11.502277", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:43) [common]"}
- {"dt": "2023-12-06T05:25:11.502920", "lvl": "debug", "msg": "Using /lava-34011 at stage 1"}
- {"dt": "2023-12-06T05:25:11.503776", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:11.504521", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-06T05:25:12.109337", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/1_ci-busybox"}
- {"dt": "2023-12-06T05:25:12.122003", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-06T05:25:12.128294", "lvl": "debug", "msg": "uuid=34011_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-06T05:25:12.130530", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:12.131717", "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-06T05:25:12.134202", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:42) [common]"}
- {"dt": "2023-12-06T05:25:12.144074", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.145287", "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": "34011_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.147942", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:42) [common]"}
- {"dt": "2023-12-06T05:25:12.163092", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.163997", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "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": "34011_1.5.4.5", "uuid": "34011_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.166769", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:42) [common]"}
- {"dt": "2023-12-06T05:25:12.179266", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/1_ci-busybox test_uuid 34011_1.5.4.5"}
- {"dt": "2023-12-06T05:25:12.181626", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.182852", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.185039", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:42) [common]"}
- {"dt": "2023-12-06T05:25:12.186391", "lvl": "debug", "msg": "Using /lava-34011 at stage 1"}
- {"dt": "2023-12-06T05:25:12.188283", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:12.189665", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-06T05:25:12.850469", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-06T05:25:12.861743", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-06T05:25:12.865332", "lvl": "debug", "msg": "uuid=34011_1.5.4.9 testdef=None"}
- {"dt": "2023-12-06T05:25:12.866745", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:12.867735", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.68", "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-06T05:25:12.870348", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:12.876273", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.877220", "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": "34011_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.879021", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:12.889414", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.890152", "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": "34011_1.5.4.9", "uuid": "34011_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.891831", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:12.899078", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/2_ci-smoke-tests-basic test_uuid 34011_1.5.4.9"}
- {"dt": "2023-12-06T05:25:12.902196", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:12.903051", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:12.904948", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:12.905809", "lvl": "debug", "msg": "Using /lava-34011 at stage 1"}
- {"dt": "2023-12-06T05:25:12.907128", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:12.908031", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-06T05:25:13.499062", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/3_ci-service-check"}
- {"dt": "2023-12-06T05:25:13.510901", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-06T05:25:13.514905", "lvl": "debug", "msg": "uuid=34011_1.5.4.13 testdef=None"}
- {"dt": "2023-12-06T05:25:13.516082", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:13.517497", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-06T05:25:13.519991", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:13.527894", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:13.528860", "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": "34011_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:13.531048", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:13.539423", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:13.540276", "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": "34011_1.5.4.13", "uuid": "34011_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:13.541997", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:13.548894", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1/tests/3_ci-service-check test_uuid 34011_1.5.4.13"}
- {"dt": "2023-12-06T05:25:13.549620", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-06T05:25:13.550119", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-06T05:25:13.551405", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:13.552073", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:13.553760", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:41) [common]"}
- {"dt": "2023-12-06T05:25:13.554420", "lvl": "debug", "msg": "Using /lava-34011 at stage 2"}
- {"dt": "2023-12-06T05:25:13.555310", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:13.555961", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/2/tests/4_screenshooter'"}
- {"dt": "2023-12-06T05:25:14.139132", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-06T05:25:14.142952", "lvl": "debug", "msg": "uuid=34011_1.5.4.17 testdef=None"}
- {"dt": "2023-12-06T05:25:14.144077", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:14.145034", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "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-06T05:25:14.146658", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:40) [common]"}
- {"dt": "2023-12-06T05:25:14.151749", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.152628", "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": "34011_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.154322", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:40) [common]"}
- {"dt": "2023-12-06T05:25:14.161224", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.162030", "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": "34011_1.5.4.17", "uuid": "34011_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.163734", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:40) [common]"}
- {"dt": "2023-12-06T05:25:14.170381", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/2/tests/4_screenshooter test_uuid 34011_1.5.4.17"}
- {"dt": "2023-12-06T05:25:14.172097", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.172958", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.174685", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:40) [common]"}
- {"dt": "2023-12-06T05:25:14.175497", "lvl": "debug", "msg": "Using /lava-34011 at stage 3"}
- {"dt": "2023-12-06T05:25:14.176651", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-06T05:25:14.177525", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-06T05:25:14.772488", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-06T05:25:14.784852", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-06T05:25:14.790588", "lvl": "debug", "msg": "uuid=34011_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-06T05:25:14.791904", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:14.792833", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.62", "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-06T05:25:14.794561", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:39) [common]"}
- {"dt": "2023-12-06T05:25:14.800843", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.801723", "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": "34011_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.803371", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:39) [common]"}
- {"dt": "2023-12-06T05:25:14.811390", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.812132", "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": "34011_1.5.4.21", "uuid": "34011_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.813920", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:39) [common]"}
- {"dt": "2023-12-06T05:25:14.822315", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/3/tests/5_ci-dumpjournal test_uuid 34011_1.5.4.21"}
- {"dt": "2023-12-06T05:25:14.824282", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:14.825139", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/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": "34011_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:14.826793", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-06T05:25:14.827583", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/0 for stage 0"}
- {"dt": "2023-12-06T05:25:14.828873", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-06T05:25:14.829997", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/1 for stage 1"}
- {"dt": "2023-12-06T05:25:14.831272", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-06T05:25:14.832094", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-06T05:25:14.832907", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-06T05:25:14.833818", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/2 for stage 2"}
- {"dt": "2023-12-06T05:25:14.835071", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-06T05:25:14.836071", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34011/lava-overlay-43kv4d94/lava-34011/3 for stage 3"}
- {"dt": "2023-12-06T05:25:14.837338", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-06T05:25:14.838517", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-06T05:25:14.839552", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:39) [common]"}
- {"dt": "2023-12-06T05:25:15.656557", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:25:15.657670", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:38) [common]"}
- {"dt": "2023-12-06T05:25:15.658951", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.659795", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-06T05:25:15.661037", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:38) [common]"}
- {"dt": "2023-12-06T05:25:15.661890", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:38) [common]"}
- {"dt": "2023-12-06T05:25:15.662681", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.663340", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.663989", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:38) [common]"}
- {"dt": "2023-12-06T05:25:15.664684", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-06T05:25:15.665378", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61984, NBD-ROOT: 34011/nbd-deploy-mw7syqjf/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-06T05:25:15.671629", "lvl": "debug", "msg": "nbd-server 61984 /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-06T05:25:15.697082", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-06T05:25:15.699436", "lvl": "debug", "msg": "output: ** (process:15507): WARNING **: 05:25:15.691: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-06T05:25:15.700078", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-06T05:25:15.700723", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-06T05:25:15.701666", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.702472", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:22) [common]"}
- {"dt": "2023-12-06T05:25:15.703426", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-06T05:25:15.712509", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-06T05:25:15.713434", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.714167", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-06T05:25:15.715464", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-06T05:25:15.716091", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-06T05:25:15.717358", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-06T05:25:15.717916", "lvl": "debug", "msg": "- {DTB}: 34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:25:15.718387", "lvl": "debug", "msg": "- {INITRD}: 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:25:15.718887", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-06T05:25:15.719348", "lvl": "debug", "msg": "- {KERNEL}: 34011/nbd-deploy-mw7syqjf/kernel/Image"}
- {"dt": "2023-12-06T05:25:15.719966", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-06T05:25:15.728642", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-06T05:25:15.729208", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61984"}
- {"dt": "2023-12-06T05:25:15.729666", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-06T05:25:15.730227", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-06T05:25:15.730992", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-06T05:25:15.731482", "lvl": "debug", "msg": "- {RAMDISK}: 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:25:15.731942", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-06T05:25:15.732605", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-06T05:25:15.733089", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-06T05:25:15.733537", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-06T05:25:15.733977", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-06T05:25:15.734616", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-06T05:25:15.735077", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-06T05:25:15.735544", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:15.736022", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:15.741380", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-06T05:25:15.741940", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-06T05:25:15.742401", "lvl": "info", "msg": "- tftp 0x68080000 34011/nbd-deploy-mw7syqjf/kernel/Image"}
- {"dt": "2023-12-06T05:25:15.742855", "lvl": "info", "msg": "- tftp 0x4a000000 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:25:15.743341", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-06T05:25:15.743789", "lvl": "info", "msg": "- tftp 0x68000000 34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:25:15.752328", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61984 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-06T05:25:15.753301", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-06T05:25:15.754028", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-06T05:25:15.754616", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.04", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:15.756005", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-06T05:25:15.756867", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-06T05:25:20.175968", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-06T05:25:20.178189", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:04) [common]"}
- {"dt": "2023-12-06T05:25:20.179048", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:56) [common]"}
- {"dt": "2023-12-06T05:25:20.179820", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:56) [common]"}
- {"dt": "2023-12-06T05:25:20.190055", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:56) [common]"}
- {"dt": "2023-12-06T05:25:20.191484", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-06T05:25:24.534780", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-06T05:25:25.604886", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-06T05:25:25.637966", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-12-06T05:25:25.741367", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:06) [common]"}
- {"dt": "2023-12-06T05:25:25.742381", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.55", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:25:25.744024", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:06) [common]"}
- {"dt": "2023-12-06T05:25:25.745147", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:50) [common]"}
- {"dt": "2023-12-06T05:25:25.745983", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-06T05:25:25.746701", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-06T05:25:25.748877", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-06T05:25:25.749735", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-06T05:25:25.750380", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-06T05:25:25.823288", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:25.865979", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:25.866969", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:25.956665", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter \uFFFD\0\0NOTICE:  BL2: R-Car Gen3 Initial Progra\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.000265", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 V\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.001485", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.042425", "lvl": "target", "msg": "NOTICE:  \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.043203", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.105873", "lvl": "target", "msg": "NOTICE:  BL2: Board is Sta\uFFFD\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.106630", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.150167", "lvl": "target", "msg": "NOTICE:  BL2: \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.150927", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.153434", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.199719", "lvl": "target", "msg": "NOTIC\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.200513", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.203026", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.251451", "lvl": "target", "msg": "NOTICE:  BL2: Boot devic\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.252248", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.252843", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.254900", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-06T05:25:26.328787", "lvl": "target", "msg": "NO\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.329521", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.332306", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.388367", "lvl": "target", "msg": "NOTICE:  BL2: Boot \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.389974", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.391371", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.392220", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-06T05:25:26.392870", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-06T05:25:26.393330", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-06T05:25:26.454991", "lvl": "target", "msg": "\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-06T05:25:26.456540", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-06T05:25:26.458514", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-06T05:25:26.459844", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-06T05:25:26.461256", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-06T05:25:26.462549", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-06T05:25:26.463832", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-06T05:25:26.465479", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-06T05:25:26.501437", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-06T05:25:26.503288", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-06T05:25:26.504775", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-06T05:25:26.506043", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-06T05:25:26.507287", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:26.508680", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:26.509964", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:26.511254", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:26.512623", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-06T05:25:26.513142", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-06T05:25:26.513897", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-06T05:25:26.543615", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-06T05:25:26.545231", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-06T05:25:26.546181", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-06T05:25:26.547076", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-06T05:25:26.547929", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-06T05:25:26.548982", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-06T05:25:26.550180", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-06T05:25:26.584318", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-06T05:25:26.585644", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-06T05:25:26.587624", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-06T05:25:26.915883", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:26.916909", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:26.917911", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-06T05:25:26.918890", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:26.920419", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-06T05:25:26.921522", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-06T05:25:27.110710", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-06T05:25:27.140106", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-06T05:25:27.141125", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-06T05:25:27.142464", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:27.143531", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:27.144464", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-06T05:25:27.145363", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:27.187495", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-06T05:25:27.354782", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-06T05:25:27.356989", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-06T05:25:27.358719", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-06T05:25:27.360879", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-06T05:25:27.404429", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-06T05:25:27.421323", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-06T05:25:27.473581", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-06T05:25:27.476368", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-06T05:25:27.478981", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:48) [common]"}
- {"dt": "2023-12-06T05:25:27.480828", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-06T05:25:27.481716", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:48)"}
- {"dt": "2023-12-06T05:25:27.500277", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-06T05:25:27.501993", "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-06T05:25:27.503279", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-06T05:25:27.605221", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-06T05:25:27.607434", "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:48)"}
- {"dt": "2023-12-06T05:25:27.620420", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-06T05:25:27.621450", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-06T05:25:27.723075", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:27.725392", "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:48)"}
- {"dt": "2023-12-06T05:25:27.741073", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:27.742343", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-06T05:25:27.843935", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:27.846204", "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:48)"}
- {"dt": "2023-12-06T05:25:27.852503", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-06T05:25:27.854945", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-06T05:25:27.956512", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-06T05:25:27.958663", "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:48)"}
- {"dt": "2023-12-06T05:25:27.964152", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-06T05:25:31.001725", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-06T05:25:31.018241", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-06T05:25:31.257816", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-06T05:25:31.768844", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-06T05:25:32.041171", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1031 ms)"}
- {"dt": "2023-12-06T05:25:32.043147", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-06T05:25:32.145994", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-06T05:25:32.148347", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:44)"}
- {"dt": "2023-12-06T05:25:32.152426", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-06T05:25:32.154790", "lvl": "input", "msg": "tftp 0x68080000 34011/nbd-deploy-mw7syqjf/kernel/Image\n"}
- {"dt": "2023-12-06T05:25:32.256338", "lvl": "target", "msg": "=>tftp 0x68080000 34011/nbd-deploy-mw7syqjf/kernel/Image"}
- {"dt": "2023-12-06T05:25:32.258063", "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:43)"}
- {"dt": "2023-12-06T05:25:32.263920", "lvl": "target", "msg": " tftp 0x68080000 34011/nbd-deploy-mw7syqjf/kernel/Image"}
- {"dt": "2023-12-06T05:25:32.299625", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-06T05:25:32.301170", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-06T05:25:32.303130", "lvl": "target", "msg": "Filename '34011/nbd-deploy-mw7syqjf/kernel/Image'."}
- {"dt": "2023-12-06T05:25:32.304067", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-06T05:25:32.893651", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-06T05:25:33.485341", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:34.060648", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:34.732359", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:35.515966", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:36.123435", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:36.794744", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:37.434102", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:38.154134", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:38.810103", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:39.481747", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:40.056799", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:40.616574", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:41.191692", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:41.751549", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:42.342942", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:42.918511", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:43.526053", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:44.117865", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:44.693713", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:45.269078", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:45.876878", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:46.452491", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:47.011726", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:47.587111", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:48.179133", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:48.754463", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:49.314363", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:49.954071", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:50.529504", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:51.073228", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:51.632707", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:52.208774", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:52.752457", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:53.295779", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:53.838785", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:54.382503", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:54.942812", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:55.517420", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:56.093665", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:56.668945", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:56.717012", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-06T05:25:56.718972", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-06T05:25:56.720827", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-06T05:25:56.732846", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-06T05:25:56.734390", "lvl": "input", "msg": "tftp 0x4a000000 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-06T05:25:56.836150", "lvl": "target", "msg": "=> tftp 0x4a000000 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:25:56.838410", "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:19)"}
- {"dt": "2023-12-06T05:25:56.842325", "lvl": "target", "msg": "tftp 0x4a000000 34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-06T05:25:56.874915", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-06T05:25:56.875649", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-06T05:25:56.876511", "lvl": "target", "msg": "Filename '34011/nbd-deploy-mw7syqjf/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-06T05:25:56.877876", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-06T05:25:57.453230", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-06T05:25:58.012680", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-06T05:25:58.115313", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-06T05:25:58.116554", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-06T05:25:58.117822", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-06T05:25:58.119078", "lvl": "target", "msg": "Bytes transferred = 2061259 (1f73cb hex)"}
- {"dt": "2023-12-06T05:25:58.121563", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-06T05:25:58.224029", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-06T05:25:58.226417", "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:17)"}
- {"dt": "2023-12-06T05:25:58.245586", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-06T05:25:58.247353", "lvl": "input", "msg": "tftp 0x68000000 34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-06T05:25:58.349975", "lvl": "target", "msg": "=> tftp 0x68000000 34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:25:58.352136", "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:17)"}
- {"dt": "2023-12-06T05:25:58.356398", "lvl": "target", "msg": "tftp 0x68000000 34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-06T05:25:58.389356", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-06T05:25:58.392858", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-06T05:25:58.395504", "lvl": "target", "msg": "Filename '34011/nbd-deploy-mw7syqjf/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-06T05:25:58.397073", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-06T05:25:58.439317", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-06T05:25:58.440627", "lvl": "target", "msg": "\t 1.3 MiB/s"}
- {"dt": "2023-12-06T05:25:58.441671", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-06T05:25:58.455483", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-06T05:25:58.457095", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61984 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-06T05:25:58.559539", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61984 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-06T05:25:58.561814", "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:17)"}
- {"dt": "2023-12-06T05:25:58.577331", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61984 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-06T05:25:58.579728", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-06T05:25:58.681649", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-06T05:25:58.683443", "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-06T05:25:58.685127", "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:17)"}
- {"dt": "2023-12-06T05:25:58.689230", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-06T05:25:58.705200", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-06T05:25:58.851343", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-06T05:25:58.854032", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-06T05:25:58.855875", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff3cb ... OK"}
- {"dt": "2023-12-06T05:25:58.857669", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000042"}
- {"dt": "2023-12-06T05:25:58.859422", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-06T05:25:58.861204", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-06T05:25:58.862938", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-06T05:25:58.864664", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-06T05:25:58.865225", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-06T05:25:58.895873", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-06T05:25:58.896528", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-06T05:25:58.897589", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-06T05:25:58.898511", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-06T05:25:58.899401", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-06T05:25:58.900303", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-06T05:25:58.901218", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-06T05:25:58.902138", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-06T05:25:58.903033", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-06T05:25:58.903922", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-06T05:25:58.905034", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-06T05:25:58.917509", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-06T05:25:58.918515", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:58.921092", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-06T05:25:58.921974", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-06T05:25:58.922715", "lvl": "target", "msg": ""}
- {"dt": "2023-12-06T05:25:58.924050", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-06T05:25:58.925021", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:31) [common]"}
- {"dt": "2023-12-06T05:25:58.925867", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "31.45", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-06T05:25:58.927485", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-06T05:25:58.928413", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:39) [common]"}
- {"dt": "2023-12-06T05:25:58.929402", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "38.75", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-06T05:25:58.930997", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-06T05:25:58.932034", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-06T05:25:58.932893", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:43) [common]"}
- {"dt": "2023-12-06T05:25:58.933583", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "43.23", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-06T05:25:58.934897", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-06T05:25:58.935670", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/initrd"}
- {"dt": "2023-12-06T05:25:58.937618", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/kernel"}
- {"dt": "2023-12-06T05:25:58.946458", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/dtb"}
- {"dt": "2023-12-06T05:25:58.947917", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34011/nbd-deploy-mw7syqjf/nbdroot"}
- {"dt": "2023-12-06T05:25:59.325128", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-06T05:25:59.326290", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-06T05:26:02.817525", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-06T05:26:02.854283", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-06T05:26:02.956885", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-06T05:26:02.958065", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.63", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-06T05:26:02.960035", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-06T05:26:02.962172", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-06T05:26:02.963399", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.964446", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.965245", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.965943", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.966649", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.967325", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.968013", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.968756", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.970388", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.971033", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.971552", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.972056", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.972817", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.973596", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.974182", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.974677", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.975161", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.975805", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.976655", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.977251", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.977750", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.978308", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.979039", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.979801", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.980436", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.980953", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.981464", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.982185", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.982954", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.983520", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.984012", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.984705", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.985298", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.985802", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.986283", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.986763", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.987258", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.987741", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.988264", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.988787", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.989286", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.989782", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.990265", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.990747", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.991228", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.991705", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.992217", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.992736", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.993218", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-06T05:26:02.994054", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-06T05:26:03.964728", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-06T05:26:03.966375", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-06T05:26:03.967756", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-06T05:26:04.069880", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-06T05:26:04.071031", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-06T05:26:04.071962", "lvl": "debug", "msg": "clean NBD port 61984"}
- {"dt": "2023-12-06T05:26:07.355014", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34011"}
- {"dt": "2023-12-06T05:26:07.445768", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34011"}
- {"dt": "2023-12-06T05:26:07.446883", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-06T05:26:07.447565", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
