- {"dt": "2023-12-14T20:03:48.331559", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-14T20:03:48.332835", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-14T20:03:48.333865", "lvl": "info", "msg": "Start time: 2023-12-14 20:03:48.333805+00:00 (UTC)"}
- {"dt": "2023-12-14T20:03:48.334985", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-14T20:03:48.550916", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-14T20:03:48.756693", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-14T20:03:48.963098", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-14T20:03:49.190961", "lvl": "info", "msg": "validate duration: 0.86"}
- {"dt": "2023-12-14T20:03:49.192413", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-14T20:03:49.193815", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-14T20:03:49.194530", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-14T20:03:49.195316", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-14T20:03:49.196002", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-14T20:03:49.197084", "lvl": "debug", "msg": "Set_port 61997"}
- {"dt": "2023-12-14T20:03:49.198408", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-14T20:03:49.199805", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-14T20:03:49.201237", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:03:49.202012", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:03:49.203074", "lvl": "debug", "msg": "total size: 2061210 (1MB)"}
- {"dt": "2023-12-14T20:03:49.203836", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-14T20:03:49.606653", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-14T20:03:49.798561", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-14T20:03:49.897699", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-14T20:03:49.904865", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-14T20:03:49.996366", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-14T20:03:50.004069", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-14T20:03:50.010662", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-14T20:03:50.015803", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-14T20:03:50.103485", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-14T20:03:50.108020", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-14T20:03:50.112665", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-14T20:03:50.118409", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-14T20:03:50.122845", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-14T20:03:50.128611", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-14T20:03:50.133133", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-14T20:03:50.192849", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-14T20:03:50.202098", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-14T20:03:50.207131", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-14T20:03:50.212865", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-14T20:03:50.216500", "lvl": "info", "msg": "1MB downloaded in 1.01s (1.94MB/s)"}
- {"dt": "2023-12-14T20:03:50.217749", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:03:50.218447", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.02", "extra": {"label": "initrd", "md5sum": "48e28fbc154b812ee10a8f949c428e97", "sha256sum": "ef39d194d6c338b3e2305d443cd9cab4e336bbdf716353e2d1ae6c2d0b79856d", "sha512sum": "8898caed2870b51d008993e3d630753bf4049e994838ad767b288cf16342ff2422793a275580ba1211a2352c201b7155d1f943d1bd605a91fd0c159186183c9f", "size": ! "2061210"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:03:50.220090", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:03:50.220895", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-14T20:03:50.221756", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-14T20:03:50.222904", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-14T20:03:50.223646", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/kernel/Image"}
- {"dt": "2023-12-14T20:03:50.224219", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-14T20:03:50.224761", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-14T20:03:50.535446", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-14T20:03:51.171736", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-14T20:03:51.437841", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-14T20:03:51.646526", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-14T20:03:51.854953", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-14T20:03:52.067911", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-14T20:03:52.262892", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-14T20:03:52.463434", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-14T20:03:52.656719", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-14T20:03:52.798922", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-14T20:03:52.996465", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-14T20:03:53.196702", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-14T20:03:53.379065", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-14T20:03:53.514581", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-14T20:03:53.702588", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-14T20:03:53.878776", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-14T20:03:54.015735", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-14T20:03:54.193353", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-14T20:03:54.322241", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-14T20:03:54.489014", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-14T20:03:54.626499", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-14T20:03:54.628181", "lvl": "info", "msg": "37MB downloaded in 4.40s (8.49MB/s)"}
- {"dt": "2023-12-14T20:03:54.629476", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:04) [common]"}
- {"dt": "2023-12-14T20:03:54.630317", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.41", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:03:54.631944", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:04) [common]"}
- {"dt": "2023-12-14T20:03:54.632822", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:55) [common]"}
- {"dt": "2023-12-14T20:03:54.633723", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:55) [common]"}
- {"dt": "2023-12-14T20:03:54.634945", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:03:54.635716", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:03:54.636323", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-14T20:03:54.637022", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-14T20:03:54.952721", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-14T20:03:55.055449", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-14T20:03:55.057880", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-14T20:03:55.059493", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-12-14T20:03:55.060672", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:03:55.061406", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.43", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:03:55.063004", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:03:55.063979", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-12-14T20:03:55.064852", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-12-14T20:03:55.066015", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29529/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-14T20:03:55.066727", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-14T20:03:55.067400", "lvl": "debug", "msg": "total size: 454057712 (433MB)"}
- {"dt": "2023-12-14T20:03:55.067989", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-14T20:03:55.391650", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-14T20:04:01.480587", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-14T20:04:07.555769", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-14T20:04:13.134112", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-14T20:04:18.251631", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-14T20:04:31.419371", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-14T20:04:36.877355", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-14T20:04:43.172299", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-14T20:04:52.059773", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-14T20:04:59.523746", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-14T20:05:06.094353", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-14T20:05:13.427442", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-14T20:05:20.223670", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-14T20:05:47.195424", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-14T20:05:53.240761", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-14T20:05:58.437729", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-14T20:06:03.815732", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-14T20:06:10.975820", "lvl": "debug", "msg": "progress  85% (368MB)"}
- {"dt": "2023-12-14T20:06:24.916120", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-14T20:06:31.296217", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-14T20:06:40.731611", "lvl": "debug", "msg": "progress 100% (433MB)"}
- {"dt": "2023-12-14T20:06:45.545589", "lvl": "info", "msg": "433MB downloaded in 170.48s (2.54MB/s)"}
- {"dt": "2023-12-14T20:06:45.547476", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:50) [common]"}
- {"dt": "2023-12-14T20:06:45.548351", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "170.48", "extra": {"label": "nbdroot", "md5sum": "2f966dc20fd8ed14a82a7de0aee7ef62", "sha256sum": "588b3a4432e4da80422ea4d83cbbd2b66b2b2ed6c9b8ef31c679f378b709e699", "sha512sum": "6d47e3f8ce37589e5a8f3c999e11e66fb28250b98569230d4e717e604109c2e77bbf5b26585a3627ff225282688493fd37bc310323b4a0330dec67a6bea52a17", "size": ! "454057712"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:45.550160", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:50) [common]"}
- {"dt": "2023-12-14T20:06:45.551035", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.554794", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g"}
- {"dt": "2023-12-14T20:06:45.556121", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin"}
- {"dt": "2023-12-14T20:06:45.557265", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/tests"}
- {"dt": "2023-12-14T20:06:45.558389", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/results"}
- {"dt": "2023-12-14T20:06:45.561957", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-add-keys"}
- {"dt": "2023-12-14T20:06:45.563549", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-add-sources"}
- {"dt": "2023-12-14T20:06:45.565030", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-background-process-start"}
- {"dt": "2023-12-14T20:06:45.566533", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-background-process-stop"}
- {"dt": "2023-12-14T20:06:45.570364", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-common-functions"}
- {"dt": "2023-12-14T20:06:45.571870", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-echo-ipv4"}
- {"dt": "2023-12-14T20:06:45.573416", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-install-packages"}
- {"dt": "2023-12-14T20:06:45.574780", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-installed-packages"}
- {"dt": "2023-12-14T20:06:45.578513", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-os-build"}
- {"dt": "2023-12-14T20:06:45.580038", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-probe-channel"}
- {"dt": "2023-12-14T20:06:45.581596", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-probe-ip"}
- {"dt": "2023-12-14T20:06:45.583036", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-target-ip"}
- {"dt": "2023-12-14T20:06:45.586712", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-target-mac"}
- {"dt": "2023-12-14T20:06:45.588323", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-target-storage"}
- {"dt": "2023-12-14T20:06:45.589940", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-case"}
- {"dt": "2023-12-14T20:06:45.593730", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-event"}
- {"dt": "2023-12-14T20:06:45.595267", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-feedback"}
- {"dt": "2023-12-14T20:06:45.596791", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-raise"}
- {"dt": "2023-12-14T20:06:45.598271", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-reference"}
- {"dt": "2023-12-14T20:06:45.600425", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-runner"}
- {"dt": "2023-12-14T20:06:45.601871", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-set"}
- {"dt": "2023-12-14T20:06:45.605275", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-test-shell"}
- {"dt": "2023-12-14T20:06:45.606817", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-14T20:06:45.608547", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-14T20:06:45.609902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/environment"}
- {"dt": "2023-12-14T20:06:45.611027", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-14T20:06:45.614016", "lvl": "debug", "msg": "- LAVA_JOB_ID=34265"}
- {"dt": "2023-12-14T20:06:45.614727", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-14T20:06:45.617856", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.620542", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:45.621495", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.622162", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-14T20:06:45.622868", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:45.626238", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.626939", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-14T20:06:45.627766", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:45.628618", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.629445", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-14T20:06:45.630358", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:04) [common]"}
- {"dt": "2023-12-14T20:06:45.631104", "lvl": "debug", "msg": "Using /lava-34265 at stage 0"}
- {"dt": "2023-12-14T20:06:45.635044", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:45.635933", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/0/tests/0_network-veth'"}
- {"dt": "2023-12-14T20:06:46.546850", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-14T20:06:46.550788", "lvl": "debug", "msg": "uuid=34265_1.5.4.1 testdef=None"}
- {"dt": "2023-12-14T20:06:46.566393", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:46.567286", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.94", "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-14T20:06:46.568930", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:03) [common]"}
- {"dt": "2023-12-14T20:06:46.577093", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:46.577812", "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": "34265_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:46.579525", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:03) [common]"}
- {"dt": "2023-12-14T20:06:46.588271", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:46.589044", "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": "34265_1.5.4.1", "uuid": "34265_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:46.592221", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:03) [common]"}
- {"dt": "2023-12-14T20:06:46.602150", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/0/tests/0_network-veth test_uuid 34265_1.5.4.1"}
- {"dt": "2023-12-14T20:06:46.605451", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:46.606205", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:46.609471", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:03) [common]"}
- {"dt": "2023-12-14T20:06:46.610346", "lvl": "debug", "msg": "Using /lava-34265 at stage 1"}
- {"dt": "2023-12-14T20:06:46.613108", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:46.614012", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-14T20:06:47.442821", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/1_ci-busybox"}
- {"dt": "2023-12-14T20:06:47.461158", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-14T20:06:47.466539", "lvl": "debug", "msg": "uuid=34265_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-14T20:06:47.467856", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:47.468694", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.86", "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-14T20:06:47.470224", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-14T20:06:47.480948", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:47.481710", "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": "34265_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:47.484315", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-14T20:06:47.494568", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:47.496928", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34265_1.5.4.5", "uuid": "34265_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:47.498673", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:02) [common]"}
- {"dt": "2023-12-14T20:06:47.509543", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/1_ci-busybox test_uuid 34265_1.5.4.5"}
- {"dt": "2023-12-14T20:06:47.511691", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:47.512484", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:47.514151", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:02) [common]"}
- {"dt": "2023-12-14T20:06:47.514940", "lvl": "debug", "msg": "Using /lava-34265 at stage 1"}
- {"dt": "2023-12-14T20:06:47.518295", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:47.519126", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-14T20:06:48.457937", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-14T20:06:48.469438", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-14T20:06:48.472913", "lvl": "debug", "msg": "uuid=34265_1.5.4.9 testdef=None"}
- {"dt": "2023-12-14T20:06:48.474152", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:48.475403", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.96", "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-14T20:06:48.477151", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-14T20:06:48.483532", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:48.484383", "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": "34265_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:48.486119", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-14T20:06:48.494511", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:48.495838", "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": "34265_1.5.4.9", "uuid": "34265_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:48.497670", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:01) [common]"}
- {"dt": "2023-12-14T20:06:48.564140", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/2_ci-smoke-tests-basic test_uuid 34265_1.5.4.9"}
- {"dt": "2023-12-14T20:06:48.584172", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:48.585034", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.07", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:48.586723", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:01) [common]"}
- {"dt": "2023-12-14T20:06:48.594822", "lvl": "debug", "msg": "Using /lava-34265 at stage 1"}
- {"dt": "2023-12-14T20:06:48.596145", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:48.597044", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-14T20:06:49.413208", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/3_ci-service-check"}
- {"dt": "2023-12-14T20:06:49.424526", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-14T20:06:49.428922", "lvl": "debug", "msg": "uuid=34265_1.5.4.13 testdef=None"}
- {"dt": "2023-12-14T20:06:49.430104", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:49.430894", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.84", "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-14T20:06:49.435032", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-14T20:06:49.442169", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:49.442922", "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": "34265_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:49.445029", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-14T20:06:49.453896", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:49.454960", "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": "34265_1.5.4.13", "uuid": "34265_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:49.458560", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:00) [common]"}
- {"dt": "2023-12-14T20:06:49.483591", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1/tests/3_ci-service-check test_uuid 34265_1.5.4.13"}
- {"dt": "2023-12-14T20:06:49.484397", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-14T20:06:49.484992", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-14T20:06:49.486364", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:49.487119", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:49.505087", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:00) [common]"}
- {"dt": "2023-12-14T20:06:49.505878", "lvl": "debug", "msg": "Using /lava-34265 at stage 2"}
- {"dt": "2023-12-14T20:06:49.506900", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:49.508349", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/2/tests/4_screenshooter'"}
- {"dt": "2023-12-14T20:06:50.434885", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-14T20:06:50.438674", "lvl": "debug", "msg": "uuid=34265_1.5.4.17 testdef=None"}
- {"dt": "2023-12-14T20:06:50.440287", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:50.441104", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.94", "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-14T20:06:50.442764", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-14T20:06:50.448926", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:50.449761", "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": "34265_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:50.451886", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-14T20:06:50.459013", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:50.459911", "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": "34265_1.5.4.17", "uuid": "34265_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:50.461647", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:26:59) [common]"}
- {"dt": "2023-12-14T20:06:50.468955", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/2/tests/4_screenshooter test_uuid 34265_1.5.4.17"}
- {"dt": "2023-12-14T20:06:50.470602", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:50.471725", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:50.473490", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:26:59) [common]"}
- {"dt": "2023-12-14T20:06:50.474350", "lvl": "debug", "msg": "Using /lava-34265 at stage 3"}
- {"dt": "2023-12-14T20:06:50.476260", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-14T20:06:50.477151", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-14T20:06:51.275910", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-14T20:06:51.294807", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-14T20:06:51.301022", "lvl": "debug", "msg": "uuid=34265_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-14T20:06:51.302123", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:51.302841", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.83", "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-14T20:06:51.309761", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:26:58) [common]"}
- {"dt": "2023-12-14T20:06:51.318856", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:51.324006", "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": "34265_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:51.325621", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:26:58) [common]"}
- {"dt": "2023-12-14T20:06:51.340776", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:51.341517", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "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": "34265_1.5.4.21", "uuid": "34265_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:51.343041", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:26:58) [common]"}
- {"dt": "2023-12-14T20:06:51.362363", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/3/tests/5_ci-dumpjournal test_uuid 34265_1.5.4.21"}
- {"dt": "2023-12-14T20:06:51.364139", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:51.364902", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/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": "34265_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:06:51.371011", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-14T20:06:51.371744", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/0 for stage 0"}
- {"dt": "2023-12-14T20:06:51.372694", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-14T20:06:51.373560", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/1 for stage 1"}
- {"dt": "2023-12-14T20:06:51.374539", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-14T20:06:51.378041", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-14T20:06:51.378809", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-14T20:06:51.379606", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/2 for stage 2"}
- {"dt": "2023-12-14T20:06:51.380643", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-14T20:06:51.381454", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34265/lava-overlay-ialb1b7g/lava-34265/3 for stage 3"}
- {"dt": "2023-12-14T20:06:51.382396", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-14T20:06:51.387918", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:06) [common]"}
- {"dt": "2023-12-14T20:06:51.388884", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:26:58) [common]"}
- {"dt": "2023-12-14T20:06:52.197379", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:06:52.198367", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:26:57) [common]"}
- {"dt": "2023-12-14T20:06:52.201262", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.202200", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:07) [common]"}
- {"dt": "2023-12-14T20:06:52.203019", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:26:57) [common]"}
- {"dt": "2023-12-14T20:06:52.203995", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:26:57) [common]"}
- {"dt": "2023-12-14T20:06:52.204884", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.205661", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.206424", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:26:57) [common]"}
- {"dt": "2023-12-14T20:06:52.207052", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-14T20:06:52.209271", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61997, NBD-ROOT: 34265/nbd-deploy-rng4m6uj/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-14T20:06:52.213207", "lvl": "debug", "msg": "nbd-server 61997 /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-14T20:06:52.243840", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-14T20:06:52.245574", "lvl": "debug", "msg": "output: ** (process:9969): WARNING **: 20:06:52.238: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-14T20:06:52.246170", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-14T20:06:52.246706", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-14T20:06:52.247752", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.248835", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:03:03) [common]"}
- {"dt": "2023-12-14T20:06:52.249763", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-14T20:06:52.250649", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-14T20:06:52.259686", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.260704", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-14T20:06:52.262110", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-14T20:06:52.262836", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-14T20:06:52.263577", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-14T20:06:52.264328", "lvl": "debug", "msg": "- {DTB}: 34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:06:52.264993", "lvl": "debug", "msg": "- {INITRD}: 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:06:52.265548", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-14T20:06:52.266083", "lvl": "debug", "msg": "- {KERNEL}: 34265/nbd-deploy-rng4m6uj/kernel/Image"}
- {"dt": "2023-12-14T20:06:52.266614", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-14T20:06:52.275229", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-14T20:06:52.276142", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61997"}
- {"dt": "2023-12-14T20:06:52.276669", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-14T20:06:52.277233", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-14T20:06:52.277761", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-14T20:06:52.278288", "lvl": "debug", "msg": "- {RAMDISK}: 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:06:52.278821", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-14T20:06:52.279481", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-14T20:06:52.280111", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-14T20:06:52.280623", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-14T20:06:52.281152", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-14T20:06:52.281654", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-14T20:06:52.282140", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-14T20:06:52.282647", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-14T20:06:52.291316", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-14T20:06:52.292072", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-14T20:06:52.292642", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-14T20:06:52.293164", "lvl": "info", "msg": "- tftp 0x68080000 34265/nbd-deploy-rng4m6uj/kernel/Image"}
- {"dt": "2023-12-14T20:06:52.293756", "lvl": "info", "msg": "- tftp 0x4a000000 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:06:52.294335", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-14T20:06:52.294860", "lvl": "info", "msg": "- tftp 0x68000000 34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:06:52.295504", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61997 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-14T20:06:52.296236", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-14T20:06:52.296947", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-14T20:06:52.297624", "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-14T20:06:52.307280", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-14T20:06:52.308113", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-14T20:06:58.264317", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-14T20:06:58.266516", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:06) [common]"}
- {"dt": "2023-12-14T20:06:58.271643", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:54) [common]"}
- {"dt": "2023-12-14T20:06:58.272572", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:54) [common]"}
- {"dt": "2023-12-14T20:06:58.273585", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:54) [common]"}
- {"dt": "2023-12-14T20:06:58.275071", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-14T20:07:02.750522", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-14T20:07:03.817847", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-14T20:07:03.858899", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-12-14T20:07:03.961086", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:06) [common]"}
- {"dt": "2023-12-14T20:07:03.961996", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.69", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:07:03.967675", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:06) [common]"}
- {"dt": "2023-12-14T20:07:03.968705", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:48) [common]"}
- {"dt": "2023-12-14T20:07:03.969450", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-14T20:07:03.970110", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-14T20:07:03.976346", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-14T20:07:03.977123", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-14T20:07:03.977706", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-14T20:07:04.031496", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Init\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.032288", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.074322", "lvl": "target", "msg": "NOTICE:  \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.075107", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.156171", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit \0NOTICE:  BL2: R-Car Gen3 Initial Progra\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.156974", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.197532", "lvl": "target", "msg": "NOTIC\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.198334", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.247398", "lvl": "target", "msg": "NOTICE:  BL2: Board is \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.248196", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.294238", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premi\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.295611", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.297401", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-14T20:07:04.342499", "lvl": "target", "msg": "NOTICE:  B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.343335", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.351236", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-14T20:07:04.395386", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is Hype\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.396827", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.397373", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-14T20:07:04.407478", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-14T20:07:04.453027", "lvl": "target", "msg": "NOTICE:  BL2:\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.453788", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.454745", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-14T20:07:04.461921", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-14T20:07:04.462617", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-14T20:07:04.520508", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded.\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-14T20:07:04.521336", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-14T20:07:04.521945", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-14T20:07:04.522456", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-14T20:07:04.522933", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-14T20:07:04.523739", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-14T20:07:04.524440", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-14T20:07:04.529604", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-14T20:07:04.567716", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-14T20:07:04.568526", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-14T20:07:04.569202", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-14T20:07:04.569873", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-14T20:07:04.570431", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:04.571017", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:04.574798", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:04.575485", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:04.576001", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-14T20:07:04.576461", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-14T20:07:04.587445", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-14T20:07:04.611597", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-14T20:07:04.612402", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-14T20:07:04.612898", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-14T20:07:04.618379", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-14T20:07:04.618966", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-14T20:07:04.619528", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-14T20:07:04.625938", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-14T20:07:04.648952", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-14T20:07:04.649746", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-14T20:07:04.655288", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-14T20:07:04.981304", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:04.982087", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:04.982697", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-14T20:07:04.983358", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:04.984037", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-14T20:07:04.991284", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-14T20:07:05.176042", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-14T20:07:05.204903", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-14T20:07:05.205673", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-14T20:07:05.206335", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:05.212341", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:05.213141", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-14T20:07:05.213812", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:05.239918", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-14T20:07:05.419393", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-14T20:07:05.420153", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-14T20:07:05.420746", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-14T20:07:05.423821", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-14T20:07:05.470449", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-14T20:07:05.488086", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-14T20:07:05.541638", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-14T20:07:05.542792", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-14T20:07:05.543806", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:47) [common]"}
- {"dt": "2023-12-14T20:07:05.544540", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-14T20:07:05.545192", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:47)"}
- {"dt": "2023-12-14T20:07:05.574666", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-14T20:07:05.577059", "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-14T20:07:05.577832", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-14T20:07:05.680911", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-14T20:07:05.681818", "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:47)"}
- {"dt": "2023-12-14T20:07:05.696707", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-14T20:07:05.698117", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-14T20:07:05.802008", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-14T20:07:05.802904", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-14T20:07:05.822679", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-14T20:07:05.824043", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-14T20:07:05.925613", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-14T20:07:05.926534", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-14T20:07:05.933595", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-14T20:07:05.937810", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-14T20:07:06.042809", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-14T20:07:06.043806", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:46)"}
- {"dt": "2023-12-14T20:07:06.073905", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-14T20:07:09.090731", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-14T20:07:09.091526", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-14T20:07:09.346231", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-14T20:07:09.858662", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-14T20:07:10.141161", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1030 ms)"}
- {"dt": "2023-12-14T20:07:10.143862", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-14T20:07:10.245787", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-14T20:07:10.246744", "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:42)"}
- {"dt": "2023-12-14T20:07:10.255632", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-14T20:07:10.264053", "lvl": "input", "msg": "tftp 0x68080000 34265/nbd-deploy-rng4m6uj/kernel/Image\n"}
- {"dt": "2023-12-14T20:07:10.365911", "lvl": "target", "msg": "=> tftp 0x68080000 34265/nbd-deploy-rng4m6uj/kernel/Image"}
- {"dt": "2023-12-14T20:07:10.366868", "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:42)"}
- {"dt": "2023-12-14T20:07:10.403413", "lvl": "target", "msg": "tftp 0x68080000 34265/nbd-deploy-rng4m6uj/kernel/Image"}
- {"dt": "2023-12-14T20:07:10.404203", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-14T20:07:10.407805", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-14T20:07:10.412020", "lvl": "target", "msg": "Filename '34265/nbd-deploy-rng4m6uj/kernel/Image'."}
- {"dt": "2023-12-14T20:07:10.412793", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-14T20:07:10.951371", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-14T20:07:11.556209", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:12.114034", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:12.641962", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:13.152857", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:13.664023", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:14.174990", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:14.702214", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:15.245099", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:15.772478", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:16.299374", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:16.842132", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:17.353252", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:17.881015", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:18.390070", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:18.918367", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:19.428163", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:19.971916", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:20.498243", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:21.010695", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:21.536892", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:22.080217", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:22.627383", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:23.149926", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:23.678096", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:24.219616", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:24.799517", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:25.337905", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:25.879745", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:26.407716", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:26.904068", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:27.399368", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:27.879387", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:28.375394", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:28.867353", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:29.363353", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:29.843388", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:30.351000", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:30.878580", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:31.422064", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:31.949626", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:32.007307", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-14T20:07:32.008037", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-14T20:07:32.008553", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-14T20:07:32.016165", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-14T20:07:32.020956", "lvl": "input", "msg": "tftp 0x4a000000 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-14T20:07:32.122965", "lvl": "target", "msg": "=> tftp 0x4a000000 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:07:32.124835", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:20)"}
- {"dt": "2023-12-14T20:07:32.128902", "lvl": "target", "msg": "tftp 0x4a000000 34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-14T20:07:32.160543", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-14T20:07:32.161290", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-14T20:07:32.161770", "lvl": "target", "msg": "Filename '34265/nbd-deploy-rng4m6uj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-14T20:07:32.165925", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-14T20:07:32.707740", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-14T20:07:33.330813", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-14T20:07:33.490000", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-14T20:07:33.490735", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-14T20:07:33.491264", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-14T20:07:33.505406", "lvl": "target", "msg": "Bytes transferred = 2061210 (1f739a hex)"}
- {"dt": "2023-12-14T20:07:33.506677", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-14T20:07:33.608674", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-14T20:07:33.610584", "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-14T20:07:33.618151", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-14T20:07:33.622183", "lvl": "input", "msg": "tftp 0x68000000 34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-14T20:07:33.724073", "lvl": "target", "msg": "=> tftp 0x68000000 34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:07:33.733343", "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-14T20:07:33.767442", "lvl": "target", "msg": "tftp 0x68000000 34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-14T20:07:33.768235", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-14T20:07:33.768817", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-14T20:07:33.769341", "lvl": "target", "msg": "Filename '34265/nbd-deploy-rng4m6uj/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-14T20:07:33.769848", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-14T20:07:33.830225", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-14T20:07:33.830990", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2023-12-14T20:07:33.832906", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-14T20:07:33.833551", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-14T20:07:33.834710", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61997 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-14T20:07:33.941950", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61997 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-14T20:07:33.943599", "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:18)"}
- {"dt": "2023-12-14T20:07:33.961124", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61997 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-14T20:07:33.967509", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-14T20:07:34.069606", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-14T20:07:34.071393", "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-14T20:07:34.072222", "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:18)"}
- {"dt": "2023-12-14T20:07:34.089048", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-14T20:07:34.090161", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-14T20:07:34.236390", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-14T20:07:34.237185", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-14T20:07:34.237805", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff39a ... OK"}
- {"dt": "2023-12-14T20:07:34.238888", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-14T20:07:34.239657", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-14T20:07:34.240758", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-14T20:07:34.241428", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-14T20:07:34.242011", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-14T20:07:34.242741", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-14T20:07:34.281998", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-14T20:07:34.283344", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-14T20:07:34.284490", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-14T20:07:34.285565", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-14T20:07:34.286254", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-14T20:07:34.286855", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-14T20:07:34.287546", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-14T20:07:34.288092", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-14T20:07:34.288649", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-14T20:07:34.289206", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-14T20:07:34.290556", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-14T20:07:34.303397", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-14T20:07:34.304631", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:34.305917", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-14T20:07:34.306635", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-14T20:07:34.307322", "lvl": "target", "msg": ""}
- {"dt": "2023-12-14T20:07:34.308473", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-14T20:07:34.309318", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:29) [common]"}
- {"dt": "2023-12-14T20:07:34.310082", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "28.76", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-14T20:07:34.314835", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-14T20:07:34.317974", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:36) [common]"}
- {"dt": "2023-12-14T20:07:34.318887", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "36.04", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-14T20:07:34.320211", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-14T20:07:34.321045", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-14T20:07:34.321783", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:42) [common]"}
- {"dt": "2023-12-14T20:07:34.322530", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "42.07", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-14T20:07:34.324036", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-14T20:07:34.325034", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/initrd"}
- {"dt": "2023-12-14T20:07:34.327553", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/kernel"}
- {"dt": "2023-12-14T20:07:34.337367", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/dtb"}
- {"dt": "2023-12-14T20:07:34.339032", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34265/nbd-deploy-rng4m6uj/nbdroot"}
- {"dt": "2023-12-14T20:07:34.342291", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-14T20:07:34.343544", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-14T20:07:38.241293", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-14T20:07:38.273687", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-14T20:07:38.375703", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-14T20:07:38.376546", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "4.03", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-14T20:07:38.377805", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-14T20:07:38.379449", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-14T20:07:38.380431", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.381047", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.381605", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.382137", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.382643", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.383199", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.383737", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.384260", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.384778", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.385298", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.385808", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.386328", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.386827", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.387370", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.387885", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.388443", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.388967", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.389464", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.389958", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.390449", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.390940", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.391481", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.391986", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.392480", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.392974", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.393473", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.393985", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.394492", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.394987", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.395530", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.396028", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.396513", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.396999", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.397483", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.397966", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.398460", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.398942", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.399464", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.399952", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.400442", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.400931", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.401418", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.401901", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.402385", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.402867", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.403389", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.403881", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.404372", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.404857", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-14T20:07:38.405685", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-14T20:07:39.383952", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-14T20:07:39.384876", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-14T20:07:39.385458", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-14T20:07:39.487079", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-14T20:07:39.487972", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-14T20:07:39.488512", "lvl": "debug", "msg": "clean NBD port 61997"}
- {"dt": "2023-12-14T20:07:44.092044", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34265"}
- {"dt": "2023-12-14T20:07:44.188752", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34265"}
- {"dt": "2023-12-14T20:07:44.189865", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-14T20:07:44.190542", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
