- {"dt": "2024-12-09T09:53:59.514361", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-09T09:53:59.514631", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-09T09:53:59.514784", "lvl": "info", "msg": "Start time: 2024-12-09 09:53:59.514775+00:00 (UTC)"}
- {"dt": "2024-12-09T09:53:59.514965", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2018.11/arm64/base/rootfs.cpio.gz?raw=true exists"}
- {"dt": "2024-12-09T09:54:00.868056", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true exists"}
- {"dt": "2024-12-09T09:54:03.037682", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb?raw=true exists"}
- {"dt": "2024-12-09T09:54:03.793760", "lvl": "info", "msg": "validate duration: 4.28"}
- {"dt": "2024-12-09T09:54:03.793997", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-09T09:54:03.794212", "lvl": "info", "msg": "start: 1 tftp-deploy (timeout 01:30:00) [common]"}
- {"dt": "2024-12-09T09:54:03.794331", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2024-12-09T09:54:03.794430", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2024-12-09T09:54:03.794604", "lvl": "debug", "msg": "Not decompressing ramdisk as can be used compressed."}
- {"dt": "2024-12-09T09:54:03.794712", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2018.11/arm64/base/rootfs.cpio.gz?raw=true"}
- {"dt": "2024-12-09T09:54:03.794786", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/ramdisk/rootfs.cpio.gz"}
- {"dt": "2024-12-09T09:54:03.794861", "lvl": "debug", "msg": "total size: 4145016 (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.251693", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.276523", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.287414", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.300594", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.305220", "lvl": "debug", "msg": "progress  20\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.313658", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.317210", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.322989", "lvl": "debug", "msg": "progress  35\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.325125", "lvl": "debug", "msg": "progress  40\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.328872", "lvl": "debug", "msg": "progress  45\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.332254", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.333935", "lvl": "debug", "msg": "progress  55\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.335736", "lvl": "debug", "msg": "progress  60\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.337703", "lvl": "debug", "msg": "progress  65\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.339391", "lvl": "debug", "msg": "progress  70\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.341060", "lvl": "debug", "msg": "progress  75\u202F% (2\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.343027", "lvl": "debug", "msg": "progress  80\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.344702", "lvl": "debug", "msg": "progress  85\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.346385", "lvl": "debug", "msg": "progress  90\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.348357", "lvl": "debug", "msg": "progress  95\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.349930", "lvl": "debug", "msg": "progress 100\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.350167", "lvl": "info", "msg": "3\u202FMB downloaded in 0.56\u202Fs (7.12\u202FMB/s)"}
- {"dt": "2024-12-09T09:54:04.350376", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:04.350471", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.56", "extra": {"label": "ramdisk", "md5sum": "4c89fd138cbd1f1cbbeea8f7aadd7364", "sha256sum": "cb162eee76c8bbf1987749dd6eb7f667c6dd8175a8550df2dd63e2fb1fd8cb05", "sha512sum": "5cdbe0e30431c92b62b86f06891a19b4c6074fdebba51f75fb9139839f14a9ab6a277a459e41619b469c4bfd9d5112bce53ebcf02dbbc2e96150acc68b23163b", "size": ! "4145016"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:04.350684", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:04.350806", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2024-12-09T09:54:04.350928", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2024-12-09T09:54:04.351163", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true"}
- {"dt": "2024-12-09T09:54:04.351238", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/kernel/Image"}
- {"dt": "2024-12-09T09:54:04.351318", "lvl": "debug", "msg": "total size: 31236608 (29\u202FMB)"}
- {"dt": "2024-12-09T09:54:04.351395", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-09T09:54:05.574154", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.586484", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.599172", "lvl": "debug", "msg": "progress  10\u202F% (3\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.612294", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.625731", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.639185", "lvl": "debug", "msg": "progress  25\u202F% (7\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.652456", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.665917", "lvl": "debug", "msg": "progress  35\u202F% (10\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.679334", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.692510", "lvl": "debug", "msg": "progress  45\u202F% (13\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.705988", "lvl": "debug", "msg": "progress  50\u202F% (14\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.719436", "lvl": "debug", "msg": "progress  55\u202F% (16\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.732737", "lvl": "debug", "msg": "progress  60\u202F% (17\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.746197", "lvl": "debug", "msg": "progress  65\u202F% (19\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.759615", "lvl": "debug", "msg": "progress  70\u202F% (20\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.772798", "lvl": "debug", "msg": "progress  75\u202F% (22\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.786386", "lvl": "debug", "msg": "progress  80\u202F% (23\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.803348", "lvl": "debug", "msg": "progress  85\u202F% (25\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.829671", "lvl": "debug", "msg": "progress  90\u202F% (26\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.856618", "lvl": "debug", "msg": "progress  95\u202F% (28\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.878355", "lvl": "debug", "msg": "progress 100\u202F% (29\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.878592", "lvl": "info", "msg": "29\u202FMB downloaded in 1.53\u202Fs (19.51\u202FMB/s)"}
- {"dt": "2024-12-09T09:54:05.878781", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-12-09T09:54:05.878864", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.53", "extra": {"label": "kernel", "md5sum": "c62d5c1f0e161443b1084db5ccd4fba0", "sha256sum": "fe49dd432ec26cad74258f7f9a639a601fef68698dbe0ea0c647c2e163fc74a0", "sha512sum": "1f5c7b57c8560658157b50d6096abaa8c568b9bdb4ce71031cb889c0ece424e2c86e1e2fdfafa15f3ec48fa963f697d126196a531c59c15dbdf52df896aa7a02", "size": ! "31236608", "success": {"sha256": "fe49dd432ec26cad74258f7f9a639a601fef68698dbe0ea0c647c2e163fc74a0"}}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:05.879056", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-12-09T09:54:05.879154", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:05.879246", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:05.879495", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb?raw=true"}
- {"dt": "2024-12-09T09:54:05.879613", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-09T09:54:05.879701", "lvl": "debug", "msg": "total size: 81477 (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:05.879789", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-09T09:54:06.211106", "lvl": "debug", "msg": "progress  40\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:06.211585", "lvl": "debug", "msg": "progress  80\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:06.211953", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-09T09:54:06.212184", "lvl": "info", "msg": "0\u202FMB downloaded in 0.33\u202Fs (0.23\u202FMB/s)"}
- {"dt": "2024-12-09T09:54:06.212387", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.212487", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.33", "extra": {"label": "dtb", "md5sum": "23bfbfc1a714fa2b88a38e4dfeb5f76d", "sha256sum": "e25571abe798349b22ddb0d1e560a499b94814724288b6a6e713b6e1f0759871", "sha512sum": "1ffd5bf71122d3c0b5978d28f74797bf5211ac8d43ab35ef565be0a012d9608098997d91baf01d304ace5a80d15753af1c28589a8ce0fbae15c03b4db2cc4788", "size": ! "81477", "success": {"sha256": "e25571abe798349b22ddb0d1e560a499b94814724288b6a6e713b6e1f0759871"}}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:06.212729", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.212865", "lvl": "debug", "msg": "start: 1.4 prepare-tftp-overlay (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:06.212987", "lvl": "debug", "msg": "start: 1.4.1 extract-nfsrootfs (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:06.213095", "lvl": "debug", "msg": "end: 1.4.1 extract-nfsrootfs (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.213202", "lvl": "debug", "msg": "start: 1.4.2 lava-overlay (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:06.213300", "lvl": "info", "msg": "[common] skipped lava-overlay - no test action."}
- {"dt": "2024-12-09T09:54:06.213402", "lvl": "debug", "msg": "end: 1.4.2 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.213507", "lvl": "debug", "msg": "start: 1.4.3 extract-overlay-ramdisk (timeout 01:29:58) [common]"}
- {"dt": "2024-12-09T09:54:06.382934", "lvl": "debug", "msg": "end: 1.4.3 extract-overlay-ramdisk (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.383133", "lvl": "debug", "msg": "start: 1.4.4 extract-modules (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.383270", "lvl": "debug", "msg": "end: 1.4.4 extract-modules (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.383384", "lvl": "debug", "msg": "start: 1.4.5 apply-overlay-tftp (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.383518", "lvl": "debug", "msg": "end: 1.4.5 apply-overlay-tftp (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.383630", "lvl": "debug", "msg": "start: 1.4.6 prepare-kernel (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.383730", "lvl": "debug", "msg": "start: 1.4.6.1 uboot-prepare-kernel (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.383826", "lvl": "debug", "msg": "end: 1.4.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.383922", "lvl": "debug", "msg": "end: 1.4.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.384017", "lvl": "debug", "msg": "start: 1.4.7 configure-preseed-file (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.384113", "lvl": "debug", "msg": "end: 1.4.7 configure-preseed-file (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:06.384208", "lvl": "debug", "msg": "start: 1.4.8 compress-ramdisk (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:06.384294", "lvl": "info", "msg": "Building ramdisk /var/lib/lava/dispatcher/tmp/38249/extract-overlay-ramdisk-_zr99_ee/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/38249/extract-overlay-ramdisk-_zr99_ee/ramdisk"}
- {"dt": "2024-12-09T09:54:06.481480", "lvl": "debug", "msg": ">> 26070 blocks\n"}
- {"dt": "2024-12-09T09:54:07.090345", "lvl": "debug", "msg": "Adding RAMdisk u-boot header."}
- {"dt": "2024-12-09T09:54:07.090568", "lvl": "debug", "msg": "mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/38249/extract-overlay-ramdisk-_zr99_ee/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/38249/extract-overlay-ramdisk-_zr99_ee/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:07.136482", "lvl": "debug", "msg": "output: Image Name:   "}
- {"dt": "2024-12-09T09:54:07.136681", "lvl": "debug", "msg": "output: Created:      Mon Dec  9 09:54:07 2024"}
- {"dt": "2024-12-09T09:54:07.136801", "lvl": "debug", "msg": "output: Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-12-09T09:54:07.136893", "lvl": "debug", "msg": "output: Data Size:    4199397 Bytes = 4100.97 KiB = 4.00 MiB"}
- {"dt": "2024-12-09T09:54:07.136973", "lvl": "debug", "msg": "output: Load Address: 00000000"}
- {"dt": "2024-12-09T09:54:07.137058", "lvl": "debug", "msg": "output: Entry Point:  00000000"}
- {"dt": "2024-12-09T09:54:07.137138", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-09T09:54:07.137311", "lvl": "debug", "msg": "rename /var/lib/lava/dispatcher/tmp/38249/extract-overlay-ramdisk-_zr99_ee/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:07.137488", "lvl": "debug", "msg": "end: 1.4.8 compress-ramdisk (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:07.137617", "lvl": "debug", "msg": "end: 1.4 prepare-tftp-overlay (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:07.137737", "lvl": "debug", "msg": "start: 1.5 lxc-create-udev-rule-action (timeout 01:29:57) [common]"}
- {"dt": "2024-12-09T09:54:07.137844", "lvl": "debug", "msg": "No LXC device requested"}
- {"dt": "2024-12-09T09:54:07.137956", "lvl": "debug", "msg": "end: 1.5 lxc-create-udev-rule-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:07.138046", "lvl": "debug", "msg": "Checking files for TFTP limit of 4294967296 bytes."}
- {"dt": "2024-12-09T09:54:07.138721", "lvl": "info", "msg": "end: 1 tftp-deploy (duration 00:00:03) [common]"}
- {"dt": "2024-12-09T09:54:07.138864", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.138989", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.139102", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:07.139208", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.139387", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-12-09T09:54:07.139481", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-09T09:54:07.139572", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2024-12-09T09:54:07.139650", "lvl": "debug", "msg": "- {DTB}: 38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-09T09:54:07.139732", "lvl": "debug", "msg": "- {INITRD}: 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:07.139813", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2024-12-09T09:54:07.139888", "lvl": "debug", "msg": "- {KERNEL}: 38249/tftp-deploy-nfnvbmzx/kernel/Image"}
- {"dt": "2024-12-09T09:54:07.139965", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-12-09T09:54:07.140058", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-12-09T09:54:07.140137", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-12-09T09:54:07.140213", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2024-12-09T09:54:07.140291", "lvl": "debug", "msg": "- {RAMDISK}: 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:07.140368", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-12-09T09:54:07.140442", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-12-09T09:54:07.140519", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-12-09T09:54:07.140598", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-12-09T09:54:07.140674", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-12-09T09:54:07.140761", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-12-09T09:54:07.140835", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-12-09T09:54:07.140913", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:07.140990", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:07.141062", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-12-09T09:54:07.141136", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-12-09T09:54:07.141213", "lvl": "info", "msg": "- tftp 0x48080000 38249/tftp-deploy-nfnvbmzx/kernel/Image"}
- {"dt": "2024-12-09T09:54:07.141288", "lvl": "info", "msg": "- tftp 0x4c000000 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:07.141362", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-12-09T09:54:07.141434", "lvl": "info", "msg": "- tftp 0x48000000 38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-09T09:54:07.141507", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-09T09:54:07.141578", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-09T09:54:07.141680", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:07.141766", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.00", "extra": {"dtb_addr": "0x48000000", "kernel_addr": "0x48080000", "ramdisk_addr": "0x4c000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:07.141982", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.142071", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2024-12-09T09:54:07.149947", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-09T09:54:07.150485", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:07.150713", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.150877", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.151004", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:20:00) [common]"}
- {"dt": "2024-12-09T09:54:07.151238", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2024-12-09T09:54:07.273212", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-09T09:54:08.336595", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-09T09:54:08.344700", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-09T09:54:08.445224", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:08.445462", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "1.29", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:08.445690", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:08.445822", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:59) [common]"}
- {"dt": "2024-12-09T09:54:08.445936", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-12-09T09:54:08.446027", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2024-12-09T09:54:08.446356", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-09T09:54:08.446465", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-09T09:54:08.446547", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-09T09:54:08.593382", "lvl": "target", "msg": "\0[    0.000146] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2024-12-09T09:54:08.593598", "lvl": "target", "msg": "[    0.004582] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2024-12-09T09:54:08.593705", "lvl": "target", "msg": "[    0.010115] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-12-09T09:54:08.594008", "lvl": "target", "msg": "[    0.014777] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2024-12-09T09:54:08.594111", "lvl": "target", "msg": "[    0.019549] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2024-12-09T09:54:08.594194", "lvl": "target", "msg": "[    0.024718] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-12-09T09:54:08.594272", "lvl": "target", "msg": "[    0.028759] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-12-09T09:54:08.596603", "lvl": "target", "msg": "[    0.034735] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:08.652084", "lvl": "target", "msg": "[    0.040596] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:08.652286", "lvl": "target", "msg": "[    0.046471] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:08.652394", "lvl": "target", "msg": "[    0.052347] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:08.652490", "lvl": "target", "msg": "[    0.058264] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2024-12-09T09:54:08.652578", "lvl": "target", "msg": "[    0.073567] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-12-09T09:54:08.652663", "lvl": "target", "msg": "[    0.083097] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-12-09T09:54:08.652764", "lvl": "target", "msg": "[    0.086396] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-12-09T09:54:08.655216", "lvl": "target", "msg": "[    0.091840] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-12-09T09:54:08.670392", "lvl": "target", "msg": "[    0.097197] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-12-09T09:54:08.710533", "lvl": "target", "msg": "[    0.114664] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2024-12-09T09:54:08.710710", "lvl": "target", "msg": "[    0.118308] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2024-12-09T09:54:08.710826", "lvl": "target", "msg": "[    0.123496] NOTICE:  BL2: Normal boot"}
- {"dt": "2024-12-09T09:54:08.710918", "lvl": "target", "msg": "[    0.127131] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2024-12-09T09:54:08.711001", "lvl": "target", "msg": "[    0.133679] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2024-12-09T09:54:08.711080", "lvl": "target", "msg": "[    0.139795] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2024-12-09T09:54:08.713547", "lvl": "target", "msg": "[    0.147395] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2024-12-09T09:54:08.729085", "lvl": "target", "msg": "[    0.156372] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2024-12-09T09:54:08.760953", "lvl": "target", "msg": "[    0.187301] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2024-12-09T09:54:08.776946", "lvl": "target", "msg": "[    0.216010] NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-12-09T09:54:09.015347", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:09.015542", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:09.015642", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2024-12-09T09:54:09.015728", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:09.015806", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-12-09T09:54:09.018424", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-09T09:54:09.386268", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-12-09T09:54:09.386463", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-12-09T09:54:09.386566", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:09.389469", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:09.389669", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-09T09:54:09.389772", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:09.421270", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2024-12-09T09:54:09.751343", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-12-09T09:54:09.751499", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-12-09T09:54:09.751595", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-12-09T09:54:09.754438", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-12-09T09:54:09.802294", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-12-09T09:54:09.802619", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-09T09:54:09.852808", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2024-12-09T09:54:09.853080", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:09.853264", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:57) [common]"}
- {"dt": "2024-12-09T09:54:09.853386", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2024-12-09T09:54:09.853500", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:57)"}
- {"dt": "2024-12-09T09:54:09.866060", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2024-12-09T09:54:09.866386", "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": "2024-12-09T09:54:09.866481", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-12-09T09:54:09.966780", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2024-12-09T09:54:09.967001", "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:57)"}
- {"dt": "2024-12-09T09:54:09.977673", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-12-09T09:54:09.977953", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2024-12-09T09:54:10.078256", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:10.078484", "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:57)"}
- {"dt": "2024-12-09T09:54:10.089363", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:10.089644", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2024-12-09T09:54:10.189944", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:10.190208", "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:57)"}
- {"dt": "2024-12-09T09:54:10.201076", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-09T09:54:10.201358", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-12-09T09:54:10.301657", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2024-12-09T09:54:10.301867", "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:57)"}
- {"dt": "2024-12-09T09:54:10.324659", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-12-09T09:54:16.403023", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2024-12-09T09:54:16.419013", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-12-09T09:54:16.658670", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-12-09T09:54:17.170530", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-12-09T09:54:17.682289", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1263 ms)"}
- {"dt": "2024-12-09T09:54:17.683110", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-12-09T09:54:17.784051", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2024-12-09T09:54:17.784942", "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:49)"}
- {"dt": "2024-12-09T09:54:17.793801", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-12-09T09:54:17.794551", "lvl": "input", "msg": "tftp 0x48080000 38249/tftp-deploy-nfnvbmzx/kernel/Image\n"}
- {"dt": "2024-12-09T09:54:17.895391", "lvl": "target", "msg": "=> tftp 0x48080000 38249/tftp-deploy-nfnvbmzx/kernel/Image"}
- {"dt": "2024-12-09T09:54:17.896115", "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:49)"}
- {"dt": "2024-12-09T09:54:17.906009", "lvl": "target", "msg": "tftp 0x48080000 38249/tftp-deploy-nfnvbmzx/kernel/Image"}
- {"dt": "2024-12-09T09:54:17.949382", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-09T09:54:17.949932", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-09T09:54:17.950252", "lvl": "target", "msg": "Filename '38249/tftp-deploy-nfnvbmzx/kernel/Image'."}
- {"dt": "2024-12-09T09:54:17.950541", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2024-12-09T09:54:23.280217", "lvl": "target", "msg": "Loading: *\bT #################################################################"}
- {"dt": "2024-12-09T09:54:23.616084", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:23.967955", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:24.303886", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:24.655694", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:24.991359", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:25.342938", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:25.678565", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:26.014364", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:26.366391", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:26.718248", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:27.038928", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:27.390806", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:27.726251", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:28.062403", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:28.414298", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:28.750021", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:29.116674", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:29.468496", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:29.836156", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:30.187883", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:30.555766", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:30.891519", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:31.243429", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:31.611255", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:31.946939", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:32.299899", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:32.634648", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:32.955375", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:33.290220", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:33.626891", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:33.945891", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:34.221825", "lvl": "target", "msg": "\t ################################################"}
- {"dt": "2024-12-09T09:54:34.222372", "lvl": "target", "msg": "\t 1.8 MiB/s"}
- {"dt": "2024-12-09T09:54:34.222681", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-09T09:54:34.224907", "lvl": "target", "msg": "Bytes transferred = 31236608 (1dca200 hex)"}
- {"dt": "2024-12-09T09:54:34.225714", "lvl": "input", "msg": "tftp 0x4c000000 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot\n"}
- {"dt": "2024-12-09T09:54:34.326580", "lvl": "target", "msg": "=> tftp 0x4c000000 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:34.327482", "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:33)"}
- {"dt": "2024-12-09T09:54:34.332766", "lvl": "target", "msg": "tftp 0x4c000000 38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-09T09:54:34.373124", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-09T09:54:34.373607", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-09T09:54:34.373963", "lvl": "target", "msg": "Filename '38249/tftp-deploy-nfnvbmzx/ramdisk/ramdisk.cpio.gz.uboot'."}
- {"dt": "2024-12-09T09:54:34.374266", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2024-12-09T09:54:34.707361", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-12-09T09:54:35.027084", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:35.362734", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:35.714435", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-09T09:54:35.852588", "lvl": "target", "msg": "\t ###########################"}
- {"dt": "2024-12-09T09:54:35.853108", "lvl": "target", "msg": "\t 2.7 MiB/s"}
- {"dt": "2024-12-09T09:54:35.853415", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-09T09:54:35.855736", "lvl": "target", "msg": "Bytes transferred = 4199461 (401425 hex)"}
- {"dt": "2024-12-09T09:54:35.856332", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-12-09T09:54:35.957229", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2024-12-09T09:54:35.958150", "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:31)"}
- {"dt": "2024-12-09T09:54:35.967681", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-12-09T09:54:35.968471", "lvl": "input", "msg": "tftp 0x48000000 38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb\n"}
- {"dt": "2024-12-09T09:54:36.069300", "lvl": "target", "msg": "=> tftp 0x48000000 38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-09T09:54:36.070052", "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:31)"}
- {"dt": "2024-12-09T09:54:36.076798", "lvl": "target", "msg": "tftp 0x48000000 38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-09T09:54:36.120123", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-09T09:54:36.120771", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-09T09:54:36.123229", "lvl": "target", "msg": "Filename '38249/tftp-deploy-nfnvbmzx/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb'."}
- {"dt": "2024-12-09T09:54:36.123679", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2024-12-09T09:54:36.139162", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2024-12-09T09:54:36.155608", "lvl": "target", "msg": "\t 2.4 MiB/s"}
- {"dt": "2024-12-09T09:54:36.156123", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-09T09:54:36.156425", "lvl": "target", "msg": "Bytes transferred = 81477 (13e45 hex)"}
- {"dt": "2024-12-09T09:54:36.156961", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'\n"}
- {"dt": "2024-12-09T09:54:36.257857", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-09T09:54:36.258727", "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:31)"}
- {"dt": "2024-12-09T09:54:36.265515", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-09T09:54:36.266298", "lvl": "input", "msg": "booti 0x48080000 0x4c000000 0x48000000\n"}
- {"dt": "2024-12-09T09:54:36.367229", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-09T09:54:36.368013", "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": "2024-12-09T09:54:36.368541", "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:31)"}
- {"dt": "2024-12-09T09:54:36.377514", "lvl": "target", "msg": "booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-09T09:54:36.421298", "lvl": "target", "msg": "## Loading init Ramdisk from Legacy Image at 4c000000 ..."}
- {"dt": "2024-12-09T09:54:36.421828", "lvl": "target", "msg": "   Image Name:   "}
- {"dt": "2024-12-09T09:54:36.422136", "lvl": "target", "msg": "   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-12-09T09:54:36.422414", "lvl": "target", "msg": "   Data Size:    4199397 Bytes = 4 MiB"}
- {"dt": "2024-12-09T09:54:36.422671", "lvl": "target", "msg": "   Load Address: 00000000"}
- {"dt": "2024-12-09T09:54:36.422939", "lvl": "target", "msg": "   Entry Point:  00000000"}
- {"dt": "2024-12-09T09:54:36.440328", "lvl": "target", "msg": "   Verifying Checksum ... OK"}
- {"dt": "2024-12-09T09:54:36.440886", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2024-12-09T09:54:36.441198", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2024-12-09T09:54:36.443427", "lvl": "target", "msg": "   Loading Ramdisk to bfa03000, end bfe043e5 ... OK"}
- {"dt": "2024-12-09T09:54:36.463222", "lvl": "target", "msg": "   Loading Device Tree to 00000000bf9ec000, end 00000000bfa02e44 ... OK"}
- {"dt": "2024-12-09T09:54:36.463756", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:36.466300", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-12-09T09:54:36.466653", "lvl": "target", "msg": ""}
- {"dt": "2024-12-09T09:54:36.467423", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:27) [common]"}
- {"dt": "2024-12-09T09:54:36.467835", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:31) [common]"}
- {"dt": "2024-12-09T09:54:36.468147", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-09T09:54:36.468450", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]', '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": "2024-12-09T09:54:36.468798", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]', '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:20:00)"}
- {"dt": "2024-12-09T09:54:38.095727", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2024-12-09T09:54:38.096889", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:29) [common]"}
- {"dt": "2024-12-09T09:54:38.097441", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2024-12-09T09:54:38.097847", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-09T09:54:38.098242", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-12-09T09:54:38.098647", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-09T09:54:38.098946", "lvl": "debug", "msg": "No login prompt set."}
- {"dt": "2024-12-09T09:54:38.099355", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-09T09:54:38.099674", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@h3ulcb:~", "h3ulcb:~#", "/ #", "Login timed out", "Login incorrect"]}
- {"dt": "2024-12-09T09:54:38.100318", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:29)"}
- {"dt": "2024-12-09T09:54:38.103062", "lvl": "target", "msg": "[    0.000000] Linux version 5.4.0-yocto-standard (oe-user@oe-host) (gcc version 9.3.0 (GCC)) #1 SMP PREEMPT Mon Jun 29 03:06:40 UTC 2020"}
- {"dt": "2024-12-09T09:54:38.103538", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-09T09:54:38.103855", "lvl": "target", "msg": "[    0.000000] efi: Getting EFI parameters from FDT:"}
- {"dt": "2024-12-09T09:54:38.104207", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-09T09:54:38.104474", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2024-12-09T09:54:38.138703", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-09T09:54:38.139255", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2024-12-09T09:54:38.139963", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-09T09:54:38.140336", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2024-12-09T09:54:38.142055", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-09T09:54:38.181761", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-12-09T09:54:38.182222", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-09T09:54:38.182503", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efe3800-0x77efe4fff]"}
- {"dt": "2024-12-09T09:54:38.182823", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-09T09:54:38.183094", "lvl": "target", "msg": "[    0.000000]   DMA32    [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2024-12-09T09:54:38.183423", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2024-12-09T09:54:38.183684", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-09T09:54:38.184321", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-09T09:54:38.224602", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2024-12-09T09:54:38.225128", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2024-12-09T09:54:38.225426", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2024-12-09T09:54:38.226122", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2024-12-09T09:54:38.226471", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2024-12-09T09:54:38.226730", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-09T09:54:38.227883", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-09T09:54:38.267547", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-09T09:54:38.268362", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-09T09:54:38.268727", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2024-12-09T09:54:38.269015", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2024-12-09T09:54:38.269322", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 31 pages/cpu s86744 r8192 d32040 u126976"}
- {"dt": "2024-12-09T09:54:38.269575", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-09T09:54:38.269879", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-12-09T09:54:38.270123", "lvl": "target", "msg": "[    0.000000] Speculative Store Bypass Disable mitigation not required"}
- {"dt": "2024-12-09T09:54:38.310577", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2020032"}
- {"dt": "2024-12-09T09:54:38.311053", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-12-09T09:54:38.311384", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp"}
- {"dt": "2024-12-09T09:54:38.311668", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.311937", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.312191", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2024-12-09T09:54:38.313773", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0xbb9ec000-0xbf9ec000] (64MB)"}
- {"dt": "2024-12-09T09:54:38.353429", "lvl": "target", "msg": "[    0.000000] Memory: 7274656K/8208384K available (14140K kernel code, 2472K rwdata, 7740K rodata, 6080K init, 493K bss, 261984K reserved, 671744K cma-reserved)"}
- {"dt": "2024-12-09T09:54:38.353910", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2024-12-09T09:54:38.354225", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-09T09:54:38.354498", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2024-12-09T09:54:38.354759", "lvl": "target", "msg": "[    0.000000] \tTasks RCU enabled."}
- {"dt": "2024-12-09T09:54:38.355924", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-09T09:54:38.396420", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2024-12-09T09:54:38.396914", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-09T09:54:38.397225", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2024-12-09T09:54:38.397495", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-09T09:54:38.397750", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x318/0x4ac with crng_init=0"}
- {"dt": "2024-12-09T09:54:38.398000", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2024-12-09T09:54:38.439405", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2024-12-09T09:54:38.439903", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2024-12-09T09:54:38.440213", "lvl": "target", "msg": "[    0.000139] Console: colour dummy device 80x25"}
- {"dt": "2024-12-09T09:54:38.440486", "lvl": "target", "msg": "[    0.000211] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2024-12-09T09:54:38.440766", "lvl": "target", "msg": "[    0.000220] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-09T09:54:38.441016", "lvl": "target", "msg": "[    0.000275] LSM: Security Framework initializing"}
- {"dt": "2024-12-09T09:54:38.441260", "lvl": "target", "msg": "[    0.000314] Smack:  Initializing."}
- {"dt": "2024-12-09T09:54:38.482316", "lvl": "target", "msg": "[    0.000317] Smack:  IPv6 port labeling enabled."}
- {"dt": "2024-12-09T09:54:38.482794", "lvl": "target", "msg": "[    0.000384] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.483101", "lvl": "target", "msg": "[    0.000429] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.483373", "lvl": "target", "msg": "[    0.023958] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-09T09:54:38.484160", "lvl": "target", "msg": "[    0.031947] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-09T09:54:38.484457", "lvl": "target", "msg": "[    0.041229] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2024-12-09T09:54:38.484706", "lvl": "target", "msg": "[    0.042858] EFI services will not be available."}
- {"dt": "2024-12-09T09:54:38.485043", "lvl": "target", "msg": "[    0.047977] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-09T09:54:38.525204", "lvl": "target", "msg": "[    0.080188] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-09T09:54:38.525691", "lvl": "target", "msg": "[    0.080226] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2024-12-09T09:54:38.525993", "lvl": "target", "msg": "[    0.112196] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-09T09:54:38.526259", "lvl": "target", "msg": "[    0.112218] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2024-12-09T09:54:38.526512", "lvl": "target", "msg": "[    0.144231] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-09T09:54:38.526753", "lvl": "target", "msg": "[    0.144253] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2024-12-09T09:54:38.526997", "lvl": "target", "msg": "[    0.176285] CPU features: detected: ARM erratum 845719"}
- {"dt": "2024-12-09T09:54:38.527235", "lvl": "target", "msg": "[    0.176297] Detected VIPT I-cache on CPU4"}
- {"dt": "2024-12-09T09:54:38.568237", "lvl": "target", "msg": "[    0.176336] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2024-12-09T09:54:38.568716", "lvl": "target", "msg": "[    0.208315] Detected VIPT I-cache on CPU5"}
- {"dt": "2024-12-09T09:54:38.569056", "lvl": "target", "msg": "[    0.208339] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2024-12-09T09:54:38.569329", "lvl": "target", "msg": "[    0.240354] Detected VIPT I-cache on CPU6"}
- {"dt": "2024-12-09T09:54:38.569584", "lvl": "target", "msg": "[    0.240378] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2024-12-09T09:54:38.569832", "lvl": "target", "msg": "[    0.272391] Detected VIPT I-cache on CPU7"}
- {"dt": "2024-12-09T09:54:38.570073", "lvl": "target", "msg": "[    0.272414] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2024-12-09T09:54:38.570313", "lvl": "target", "msg": "[    0.272491] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2024-12-09T09:54:38.571486", "lvl": "target", "msg": "[    0.272513] SMP: Total of 8 processors activated."}
- {"dt": "2024-12-09T09:54:38.611209", "lvl": "target", "msg": "[    0.272518] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-09T09:54:38.611689", "lvl": "target", "msg": "[    0.272523] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-09T09:54:38.612000", "lvl": "target", "msg": "[    0.282835] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-09T09:54:38.612276", "lvl": "target", "msg": "[    0.282867] alternatives: patching kernel code"}
- {"dt": "2024-12-09T09:54:38.612530", "lvl": "target", "msg": "[    0.283674] devtmpfs: initialized"}
- {"dt": "2024-12-09T09:54:38.612802", "lvl": "target", "msg": "[    0.290158] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-09T09:54:38.613059", "lvl": "target", "msg": "[    0.290172] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.613929", "lvl": "target", "msg": "[    0.299750] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-09T09:54:38.654098", "lvl": "target", "msg": "[    0.301112] DMI not present or invalid."}
- {"dt": "2024-12-09T09:54:38.654587", "lvl": "target", "msg": "[    0.301389] NET: Registered protocol family 16"}
- {"dt": "2024-12-09T09:54:38.654889", "lvl": "target", "msg": "[    0.302184] DMA: preallocated 256 KiB pool for atomic allocations"}
- {"dt": "2024-12-09T09:54:38.655163", "lvl": "target", "msg": "[    0.302196] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-09T09:54:38.655416", "lvl": "target", "msg": "[    0.302325] audit: type=2000 audit(0.300:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-09T09:54:38.655660", "lvl": "target", "msg": "[    0.304459] cpuidle: using governor menu"}
- {"dt": "2024-12-09T09:54:38.655899", "lvl": "target", "msg": "[    0.304663] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-09T09:54:38.657315", "lvl": "target", "msg": "[    0.305768] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-09T09:54:38.697130", "lvl": "target", "msg": "[    0.308440] sh-pfc e6060000.pin-controller: IRQ index 0 not found"}
- {"dt": "2024-12-09T09:54:38.697660", "lvl": "target", "msg": "[    0.308683] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered"}
- {"dt": "2024-12-09T09:54:38.697987", "lvl": "target", "msg": "[    0.331008] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-09T09:54:38.698337", "lvl": "target", "msg": "[    0.331016] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-09T09:54:38.698645", "lvl": "target", "msg": "[    0.331020] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-09T09:54:38.698932", "lvl": "target", "msg": "[    0.331025] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-09T09:54:38.700392", "lvl": "target", "msg": "[    0.332865] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-12-09T09:54:38.740037", "lvl": "target", "msg": "[    0.335596] ACPI: Interpreter disabled."}
- {"dt": "2024-12-09T09:54:38.740521", "lvl": "target", "msg": "[    0.338545] rcar-avs: use avs value: 0"}
- {"dt": "2024-12-09T09:54:38.740869", "lvl": "target", "msg": "[    0.338557] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2024-12-09T09:54:38.741153", "lvl": "target", "msg": "[    0.338564] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2024-12-09T09:54:38.741413", "lvl": "target", "msg": "[    0.338571] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2024-12-09T09:54:38.741660", "lvl": "target", "msg": "[    0.338577] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2024-12-09T09:54:38.741913", "lvl": "target", "msg": "[    0.338584] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2024-12-09T09:54:38.742177", "lvl": "target", "msg": "[    0.339652] iommu: Default domain type: Translated "}
- {"dt": "2024-12-09T09:54:38.783007", "lvl": "target", "msg": "[    0.340139] ipmmu-vmsa e67b0000.mmu: IPMMU context 0 is reserved"}
- {"dt": "2024-12-09T09:54:38.783482", "lvl": "target", "msg": "[    0.340815] vgaarb: loaded"}
- {"dt": "2024-12-09T09:54:38.783800", "lvl": "target", "msg": "[    0.341025] SCSI subsystem initialized"}
- {"dt": "2024-12-09T09:54:38.784070", "lvl": "target", "msg": "[    0.341281] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-09T09:54:38.784327", "lvl": "target", "msg": "[    0.341305] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-09T09:54:38.785288", "lvl": "target", "msg": "[    0.341358] usbcore: registered new device driver usb"}
- {"dt": "2024-12-09T09:54:38.785590", "lvl": "target", "msg": "[    0.342336] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2024-12-09T09:54:38.785848", "lvl": "target", "msg": "[    0.342625] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-09T09:54:38.786919", "lvl": "target", "msg": "[    0.342641] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-09T09:54:38.825980", "lvl": "target", "msg": "[    0.342688] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-09T09:54:38.826448", "lvl": "target", "msg": "[    0.342693] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-09T09:54:38.826772", "lvl": "target", "msg": "[    0.342701] PTP clock support registered"}
- {"dt": "2024-12-09T09:54:38.827048", "lvl": "target", "msg": "[    0.342831] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-12-09T09:54:38.827305", "lvl": "target", "msg": "[    0.344156] FPGA manager framework"}
- {"dt": "2024-12-09T09:54:38.827555", "lvl": "target", "msg": "[    0.344202] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-09T09:54:38.827803", "lvl": "target", "msg": "[    0.344488] NetLabel: Initializing"}
- {"dt": "2024-12-09T09:54:38.828043", "lvl": "target", "msg": "[    0.344493] NetLabel:  domain hash size = 128"}
- {"dt": "2024-12-09T09:54:38.829100", "lvl": "target", "msg": "[    0.344496] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-12-09T09:54:38.868916", "lvl": "target", "msg": "[    0.344533] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-12-09T09:54:38.869384", "lvl": "target", "msg": "[    0.344857] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-09T09:54:38.869702", "lvl": "target", "msg": "[    0.663246] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-09T09:54:38.869989", "lvl": "target", "msg": "[    0.663298] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-12-09T09:54:38.870280", "lvl": "target", "msg": "[    0.663445] pnp: PnP ACPI: disabled"}
- {"dt": "2024-12-09T09:54:38.870545", "lvl": "target", "msg": "[    0.667091] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-12-09T09:54:38.870796", "lvl": "target", "msg": "[    0.667868] NET: Registered protocol family 2"}
- {"dt": "2024-12-09T09:54:38.911891", "lvl": "target", "msg": "[    0.668131] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.913186", "lvl": "target", "msg": "[    0.668195] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.913565", "lvl": "target", "msg": "[    0.668476] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.913861", "lvl": "target", "msg": "[    0.669088] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-09T09:54:38.914127", "lvl": "target", "msg": "[    0.669217] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.914384", "lvl": "target", "msg": "[    0.669324] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-09T09:54:38.915434", "lvl": "target", "msg": "[    0.669549] NET: Registered protocol family 1"}
- {"dt": "2024-12-09T09:54:38.954832", "lvl": "target", "msg": "[    0.669925] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-09T09:54:38.955301", "lvl": "target", "msg": "[    0.669930] RPC: Registered udp transport module."}
- {"dt": "2024-12-09T09:54:38.955619", "lvl": "target", "msg": "[    0.669934] RPC: Registered tcp transport module."}
- {"dt": "2024-12-09T09:54:38.955891", "lvl": "target", "msg": "[    0.669937] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-09T09:54:38.956151", "lvl": "target", "msg": "[    0.669946] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-09T09:54:38.956399", "lvl": "target", "msg": "[    0.670060] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-09T09:54:38.956643", "lvl": "target", "msg": "[    0.808936] Freeing initrd memory: 4100K"}
- {"dt": "2024-12-09T09:54:38.958308", "lvl": "target", "msg": "[    0.809755] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2024-12-09T09:54:38.997892", "lvl": "target", "msg": "[    0.810006] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2024-12-09T09:54:38.998388", "lvl": "target", "msg": "[    0.810435] kvm [1]: IPA Size Limit: 40bits"}
- {"dt": "2024-12-09T09:54:38.998727", "lvl": "target", "msg": "[    0.810959] kvm [1]: vgic interrupt IRQ1"}
- {"dt": "2024-12-09T09:54:38.999016", "lvl": "target", "msg": "[    0.811112] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-12-09T09:54:38.999284", "lvl": "target", "msg": "[    0.842912] Initialise system trusted keyrings"}
- {"dt": "2024-12-09T09:54:38.999537", "lvl": "target", "msg": "[    0.843012] workingset: timestamp_bits=44 max_order=21 bucket_order=0"}
- {"dt": "2024-12-09T09:54:38.999789", "lvl": "target", "msg": "[    0.846305] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-12-09T09:54:39.001062", "lvl": "target", "msg": "[    0.846754] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-09T09:54:39.040717", "lvl": "target", "msg": "[    0.846775] Key type id_resolver registered"}
- {"dt": "2024-12-09T09:54:39.041206", "lvl": "target", "msg": "[    0.846780] Key type id_legacy registered"}
- {"dt": "2024-12-09T09:54:39.041524", "lvl": "target", "msg": "[    0.846789] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-09T09:54:39.042529", "lvl": "target", "msg": "[    0.846957] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-12-09T09:54:39.042830", "lvl": "target", "msg": "[    0.857854] Key type asymmetric registered"}
- {"dt": "2024-12-09T09:54:39.043161", "lvl": "target", "msg": "[    0.857863] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-09T09:54:39.043443", "lvl": "target", "msg": "[    0.857903] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2024-12-09T09:54:39.043698", "lvl": "target", "msg": "[    0.857909] io scheduler mq-deadline registered"}
- {"dt": "2024-12-09T09:54:39.044007", "lvl": "target", "msg": "[    0.857914] io scheduler kyber registered"}
- {"dt": "2024-12-09T09:54:39.083788", "lvl": "target", "msg": "[    0.870665] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-09T09:54:39.084276", "lvl": "target", "msg": "[    0.870881] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2024-12-09T09:54:39.084597", "lvl": "target", "msg": "[    0.871074] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2024-12-09T09:54:39.084907", "lvl": "target", "msg": "[    0.871262] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-09T09:54:39.085173", "lvl": "target", "msg": "[    0.871453] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2024-12-09T09:54:39.085422", "lvl": "target", "msg": "[    0.871642] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2024-12-09T09:54:39.085666", "lvl": "target", "msg": "[    0.871846] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2024-12-09T09:54:39.087028", "lvl": "target", "msg": "[    0.872026] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2024-12-09T09:54:39.126676", "lvl": "target", "msg": "[    0.873718] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2024-12-09T09:54:39.127211", "lvl": "target", "msg": "[    0.873743] rcar-pcie fe000000.pcie:    IO 0xfe100000..0xfe1fffff -> 0x00000000"}
- {"dt": "2024-12-09T09:54:39.127562", "lvl": "target", "msg": "[    0.873760] rcar-pcie fe000000.pcie:   MEM 0xfe200000..0xfe3fffff -> 0xfe200000"}
- {"dt": "2024-12-09T09:54:39.127917", "lvl": "target", "msg": "[    0.873774] rcar-pcie fe000000.pcie:   MEM 0x30000000..0x37ffffff -> 0x30000000"}
- {"dt": "2024-12-09T09:54:39.128202", "lvl": "target", "msg": "[    0.873783] rcar-pcie fe000000.pcie:   MEM 0x38000000..0x3fffffff -> 0x38000000"}
- {"dt": "2024-12-09T09:54:39.128539", "lvl": "target", "msg": "[    0.940486] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2024-12-09T09:54:39.169608", "lvl": "target", "msg": "[    0.940620] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2024-12-09T09:54:39.170092", "lvl": "target", "msg": "[    0.940637] rcar-pcie ee800000.pcie:    IO 0xee900000..0xee9fffff -> 0x00000000"}
- {"dt": "2024-12-09T09:54:39.170421", "lvl": "target", "msg": "[    0.940651] rcar-pcie ee800000.pcie:   MEM 0xeea00000..0xeebfffff -> 0xeea00000"}
- {"dt": "2024-12-09T09:54:39.170710", "lvl": "target", "msg": "[    0.940664] rcar-pcie ee800000.pcie:   MEM 0xc0000000..0xc7ffffff -> 0xc0000000"}
- {"dt": "2024-12-09T09:54:39.171012", "lvl": "target", "msg": "[    0.940673] rcar-pcie ee800000.pcie:   MEM 0xc8000000..0xcfffffff -> 0xc8000000"}
- {"dt": "2024-12-09T09:54:39.171296", "lvl": "target", "msg": "[    1.004485] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2024-12-09T09:54:39.171597", "lvl": "target", "msg": "[    1.006547] EINJ: ACPI disabled."}
- {"dt": "2024-12-09T09:54:39.212520", "lvl": "target", "msg": "[    1.030370] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-12-09T09:54:39.213027", "lvl": "target", "msg": "[    1.032384] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-12-09T09:54:39.213360", "lvl": "target", "msg": "[    1.032735] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 37, base_baud = 0) is a hscif"}
- {"dt": "2024-12-09T09:54:39.213640", "lvl": "target", "msg": "[    1.033165] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 38, base_baud = 0) is a hscif"}
- {"dt": "2024-12-09T09:54:39.213897", "lvl": "target", "msg": "[    1.033377] sh-pfc e6060000.pin-controller: pin GP_6_26 already requested by ee0a0200.usb-phy; cannot claim for e6560000.serial"}
- {"dt": "2024-12-09T09:54:39.215773", "lvl": "target", "msg": "[    1.033385] sh-pfc e6060000.pin-controller: pin-218 (e6560000.serial) status -22"}
- {"dt": "2024-12-09T09:54:39.255538", "lvl": "target", "msg": "[    1.033391] sh-pfc e6060000.pin-controller: could not request pin 218 (GP_6_26) from group hscif2_data_c  on device sh-pfc"}
- {"dt": "2024-12-09T09:54:39.256016", "lvl": "target", "msg": "[    1.033396] sh-sci e6560000.serial: Error applying setting, reverse things back"}
- {"dt": "2024-12-09T09:54:39.256330", "lvl": "target", "msg": "[    1.033406] sh-sci: probe of e6560000.serial failed with error -22"}
- {"dt": "2024-12-09T09:54:39.256651", "lvl": "target", "msg": "[    1.033635] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 127, base_baud = 0) is a scif"}
- {"dt": "2024-12-09T09:54:39.258718", "lvl": "target", "msg": "[    1.033975] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 128, base_baud = 0) is a scif"}
- {"dt": "2024-12-09T09:54:39.303153", "lvl": "target", "msg": "[    2.233206] printk: console [ttySC0] enabled"}
- {"dt": "2024-12-09T09:54:39.303636", "lvl": "target", "msg": "[    2.237784] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 129, base_baud = 0) is a scif"}
- {"dt": "2024-12-09T09:54:39.303970", "lvl": "target", "msg": "[    2.246916] msm_serial: driver initialized"}
- {"dt": "2024-12-09T09:54:39.304256", "lvl": "target", "msg": "[    2.253944] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-12-09T09:54:39.305268", "lvl": "target", "msg": "[    2.260530] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-12-09T09:54:39.305576", "lvl": "target", "msg": "[    2.266827] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-09T09:54:39.336044", "lvl": "target", "msg": "[    2.276693] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-09T09:54:39.336532", "lvl": "target", "msg": "[    2.284718] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-09T09:54:39.339247", "lvl": "target", "msg": "[    2.294498] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-09T09:54:39.339688", "lvl": "target", "msg": "[    2.307915] brd: module loaded"}
- {"dt": "2024-12-09T09:54:39.355054", "lvl": "target", "msg": "[    2.316388] loop: module loaded"}
- {"dt": "2024-12-09T09:54:39.464270", "lvl": "target", "msg": "[    2.400126] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2024-12-09T09:54:39.464767", "lvl": "target", "msg": "[    2.404355] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-12-09T09:54:39.465101", "lvl": "target", "msg": "[    2.410266] thunder_xcv, ver 1.0"}
- {"dt": "2024-12-09T09:54:39.465382", "lvl": "target", "msg": "[    2.413508] thunder_bgx, ver 1.0"}
- {"dt": "2024-12-09T09:54:39.465642", "lvl": "target", "msg": "[    2.416729] nicpf, ver 1.0"}
- {"dt": "2024-12-09T09:54:39.465890", "lvl": "target", "msg": "[    2.420089] hclge is initializing"}
- {"dt": "2024-12-09T09:54:39.466136", "lvl": "target", "msg": "[    2.423388] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-12-09T09:54:39.466385", "lvl": "target", "msg": "[    2.430572] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-12-09T09:54:39.467578", "lvl": "target", "msg": "[    2.435886] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k"}
- {"dt": "2024-12-09T09:54:39.510943", "lvl": "target", "msg": "[    2.441689] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-12-09T09:54:39.511420", "lvl": "target", "msg": "[    2.447596] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k"}
- {"dt": "2024-12-09T09:54:39.511748", "lvl": "target", "msg": "[    2.454521] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-12-09T09:54:39.512030", "lvl": "target", "msg": "[    2.460086] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k"}
- {"dt": "2024-12-09T09:54:39.512295", "lvl": "target", "msg": "[    2.467876] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-12-09T09:54:39.512547", "lvl": "target", "msg": "[    2.474120] sky2: driver version 1.30"}
- {"dt": "2024-12-09T09:54:39.512821", "lvl": "target", "msg": "[    2.478481] libphy: ravb_mii: probed"}
- {"dt": "2024-12-09T09:54:39.550962", "lvl": "target", "msg": "[    2.484566] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 122."}
- {"dt": "2024-12-09T09:54:39.551436", "lvl": "target", "msg": "[    2.494300] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-12-09T09:54:39.551753", "lvl": "target", "msg": "[    2.501028] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-12-09T09:54:39.552030", "lvl": "target", "msg": "[    2.507528] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-12-09T09:54:39.554125", "lvl": "target", "msg": "[    2.511963] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-12-09T09:54:39.598878", "lvl": "target", "msg": "[    2.544921] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2024-12-09T09:54:39.599348", "lvl": "target", "msg": "[    2.550574] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-09T09:54:39.602085", "lvl": "target", "msg": "[    2.558436] ehci-platform ee080100.usb: irq 175, io mem 0xee080100"}
- {"dt": "2024-12-09T09:54:39.641934", "lvl": "target", "msg": "[    2.576864] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-09T09:54:39.642422", "lvl": "target", "msg": "[    2.583374] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:39.642745", "lvl": "target", "msg": "[    2.587122] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:39.643041", "lvl": "target", "msg": "[    2.591306] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-09T09:54:39.643304", "lvl": "target", "msg": "[    2.596947] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-09T09:54:39.645205", "lvl": "target", "msg": "[    2.604798] ehci-platform ee0a0100.usb: irq 176, io mem 0xee0a0100"}
- {"dt": "2024-12-09T09:54:39.692827", "lvl": "target", "msg": "[    2.624868] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-09T09:54:39.693328", "lvl": "target", "msg": "[    2.631277] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:39.693638", "lvl": "target", "msg": "[    2.635024] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:39.693982", "lvl": "target", "msg": "[    2.639171] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-09T09:54:39.694253", "lvl": "target", "msg": "[    2.644812] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-09T09:54:39.696016", "lvl": "target", "msg": "[    2.652650] ehci-platform ee0c0100.usb: irq 177, io mem 0xee0c0100"}
- {"dt": "2024-12-09T09:54:39.719844", "lvl": "target", "msg": "[    2.672866] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-09T09:54:39.720259", "lvl": "target", "msg": "[    2.679263] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:39.722929", "lvl": "target", "msg": "[    2.683008] hub 3-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:39.767787", "lvl": "target", "msg": "[    2.712902] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-09T09:54:39.768262", "lvl": "target", "msg": "[    2.718544] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-09T09:54:39.768588", "lvl": "target", "msg": "[    2.726374] ehci-platform ee0e0100.usb: irq 178, io mem 0xee0e0100"}
- {"dt": "2024-12-09T09:54:39.811790", "lvl": "target", "msg": "[    2.744860] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-09T09:54:39.812258", "lvl": "target", "msg": "[    2.751262] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:39.812580", "lvl": "target", "msg": "[    2.755012] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:39.812896", "lvl": "target", "msg": "[    2.759156] ehci-orion: EHCI orion driver"}
- {"dt": "2024-12-09T09:54:39.813223", "lvl": "target", "msg": "[    2.763365] ehci-exynos: EHCI EXYNOS driver"}
- {"dt": "2024-12-09T09:54:39.813512", "lvl": "target", "msg": "[    2.767631] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-12-09T09:54:39.813804", "lvl": "target", "msg": "[    2.773796] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-12-09T09:54:39.814076", "lvl": "target", "msg": "[    2.778252] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-12-09T09:54:39.815089", "lvl": "target", "msg": "[    2.783602] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-09T09:54:39.837676", "lvl": "target", "msg": "[    2.790288] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2024-12-09T09:54:39.840863", "lvl": "target", "msg": "[    2.798123] ohci-platform ee080000.usb: irq 175, io mem 0xee080000"}
- {"dt": "2024-12-09T09:54:39.953690", "lvl": "target", "msg": "[    2.891839] hub 5-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:39.954151", "lvl": "target", "msg": "[    2.895585] hub 5-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:39.954471", "lvl": "target", "msg": "[    2.899703] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-09T09:54:39.954752", "lvl": "target", "msg": "[    2.906381] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2024-12-09T09:54:39.956694", "lvl": "target", "msg": "[    2.914209] ohci-platform ee0a0000.usb: irq 176, io mem 0xee0a0000"}
- {"dt": "2024-12-09T09:54:40.070464", "lvl": "target", "msg": "[    3.007843] hub 6-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:40.071085", "lvl": "target", "msg": "[    3.011590] hub 6-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:40.071489", "lvl": "target", "msg": "[    3.015696] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-09T09:54:40.071787", "lvl": "target", "msg": "[    3.022374] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2024-12-09T09:54:40.073619", "lvl": "target", "msg": "[    3.030206] ohci-platform ee0c0000.usb: irq 177, io mem 0xee0c0000"}
- {"dt": "2024-12-09T09:54:40.186443", "lvl": "target", "msg": "[    3.123827] hub 7-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:40.187020", "lvl": "target", "msg": "[    3.127573] hub 7-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:40.187360", "lvl": "target", "msg": "[    3.131688] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-09T09:54:40.187643", "lvl": "target", "msg": "[    3.138367] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2024-12-09T09:54:40.189579", "lvl": "target", "msg": "[    3.146194] ohci-platform ee0e0000.usb: irq 178, io mem 0xee0e0000"}
- {"dt": "2024-12-09T09:54:40.296416", "lvl": "target", "msg": "[    3.239115] hub 8-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:40.297023", "lvl": "target", "msg": "[    3.242860] hub 8-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:40.297356", "lvl": "target", "msg": "[    3.247013] ohci-exynos: OHCI EXYNOS driver"}
- {"dt": "2024-12-09T09:54:40.297638", "lvl": "target", "msg": "[    3.251770] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-09T09:54:40.299465", "lvl": "target", "msg": "[    3.256982] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2024-12-09T09:54:40.343546", "lvl": "target", "msg": "[    3.280188] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2024-12-09T09:54:40.344082", "lvl": "target", "msg": "[    3.289303] xhci-hcd ee000000.usb: irq 173, io mem 0xee000000"}
- {"dt": "2024-12-09T09:54:40.344405", "lvl": "target", "msg": "[    3.295423] hub 9-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:40.344730", "lvl": "target", "msg": "[    3.299175] hub 9-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:40.345048", "lvl": "target", "msg": "[    3.303215] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-09T09:54:40.345304", "lvl": "target", "msg": "[    3.308421] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2024-12-09T09:54:40.346774", "lvl": "target", "msg": "[    3.315871] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-09T09:54:40.389180", "lvl": "target", "msg": "[    3.322145] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2024-12-09T09:54:40.389747", "lvl": "target", "msg": "[    3.330540] hub 10-0:1.0: USB hub found"}
- {"dt": "2024-12-09T09:54:40.390106", "lvl": "target", "msg": "[    3.334377] hub 10-0:1.0: 1 port detected"}
- {"dt": "2024-12-09T09:54:40.391125", "lvl": "target", "msg": "[    3.338968] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-09T09:54:40.391434", "lvl": "target", "msg": "[    3.345011] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-09T09:54:40.391697", "lvl": "target", "msg": "[    3.351522] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-09T09:54:40.391949", "lvl": "target", "msg": "[    3.359026] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2024-12-09T09:54:40.447086", "lvl": "target", "msg": "[    3.364728] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2024-12-09T09:54:40.447658", "lvl": "target", "msg": "[    3.369910] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2024-12-09T09:54:40.448032", "lvl": "target", "msg": "[    3.374492] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2024-12-09T09:54:40.448347", "lvl": "target", "msg": "[    3.380181] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2024-12-09T09:54:40.448623", "lvl": "target", "msg": "[    3.385349] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2024-12-09T09:54:40.448928", "lvl": "target", "msg": "[    3.390594] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2024-12-09T09:54:40.449999", "lvl": "target", "msg": "[    3.397728] i2c /dev entries driver"}
- {"dt": "2024-12-09T09:54:40.450364", "lvl": "target", "msg": "[    3.410428] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2024-12-09T09:54:40.450654", "lvl": "target", "msg": "[    3.414643] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2024-12-09T09:54:40.450915", "lvl": "target", "msg": "[    3.419038] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2024-12-09T09:54:40.497214", "lvl": "target", "msg": "[    3.424999] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2024-12-09T09:54:40.497797", "lvl": "target", "msg": "[    3.429377] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2024-12-09T09:54:40.498191", "lvl": "target", "msg": "[    3.433916] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-09T09:54:40.498504", "lvl": "target", "msg": "[    3.440377] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2024-12-09T09:54:40.498783", "lvl": "target", "msg": "[    3.449300] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2024-12-09T09:54:40.500143", "lvl": "target", "msg": "[    3.459449] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2024-12-09T09:54:40.516414", "lvl": "target", "msg": "[    3.484403] random: fast init done"}
- {"dt": "2024-12-09T09:54:40.616236", "lvl": "target", "msg": "[    3.557791] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2024-12-09T09:54:40.616778", "lvl": "target", "msg": "[    3.566718] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2024-12-09T09:54:40.619345", "lvl": "target", "msg": "[    3.576857] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2024-12-09T09:54:40.680303", "lvl": "target", "msg": "[    3.636864] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2024-12-09T09:54:40.741736", "lvl": "target", "msg": "[    3.674921] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2024-12-09T09:54:40.742219", "lvl": "target", "msg": "[    3.679884] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2024-12-09T09:54:40.742543", "lvl": "target", "msg": "[    3.688077] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2024-12-09T09:54:40.742826", "lvl": "target", "msg": "[    3.694125] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2024-12-09T09:54:40.743806", "lvl": "target", "msg": "[    3.700187] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2024-12-09T09:54:40.744094", "lvl": "target", "msg": "[    3.706225] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2024-12-09T09:54:40.745111", "lvl": "target", "msg": "[    3.712265] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2024-12-09T09:54:40.804225", "lvl": "target", "msg": "[    3.718299] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2024-12-09T09:54:40.804708", "lvl": "target", "msg": "[    3.724374] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2024-12-09T09:54:40.805093", "lvl": "target", "msg": "[    3.730408] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2024-12-09T09:54:40.805379", "lvl": "target", "msg": "[    3.738509] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2024-12-09T09:54:40.805643", "lvl": "target", "msg": "[    3.750786] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2024-12-09T09:54:40.805895", "lvl": "target", "msg": "[    3.761960] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2024-12-09T09:54:40.807500", "lvl": "target", "msg": "[    3.773063] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2024-12-09T09:54:40.842057", "lvl": "target", "msg": "[    3.783569] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-09T09:54:40.842536", "lvl": "target", "msg": "[    3.789729] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-09T09:54:40.842864", "lvl": "target", "msg": "[    3.795142] renesas_sdhi_internal_dmac ee140000.sd: Using IPMMU context 1"}
- {"dt": "2024-12-09T09:54:40.845263", "lvl": "target", "msg": "[    3.801904] renesas_sdhi_internal_dmac ee140000.sd: Adding to iommu group 0"}
- {"dt": "2024-12-09T09:54:40.927991", "lvl": "target", "msg": "[    3.857637] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz"}
- {"dt": "2024-12-09T09:54:40.928468", "lvl": "target", "msg": "[    3.867385] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-12-09T09:54:40.928824", "lvl": "target", "msg": "[    3.874415] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-09T09:54:40.929114", "lvl": "target", "msg": "[    3.882625] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-09T09:54:40.929382", "lvl": "target", "msg": "[    3.889869] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-09T09:54:40.929639", "lvl": "target", "msg": "[    3.895436] usbhid: USB HID core driver"}
- {"dt": "2024-12-09T09:54:40.946716", "lvl": "target", "msg": "[    3.900970] irq: type mismatch, failed to map hwirq-39 for interrupt-controller@f1010000!"}
- {"dt": "2024-12-09T09:54:41.027921", "lvl": "target", "msg": "[    3.963473] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2024-12-09T09:54:41.028464", "lvl": "target", "msg": "[    3.969094] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2024-12-09T09:54:41.028844", "lvl": "target", "msg": "[    3.973989] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2024-12-09T09:54:41.029219", "lvl": "target", "msg": "[    3.980255] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2024-12-09T09:54:41.031019", "lvl": "target", "msg": "[    3.986445] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (234:0)"}
- {"dt": "2024-12-09T09:54:41.031454", "lvl": "target", "msg": "[    3.995630]  mmcblk0: p1 p2"}
- {"dt": "2024-12-09T09:54:41.114489", "lvl": "target", "msg": "[    4.035196] optee: probing for conduit method from DT."}
- {"dt": "2024-12-09T09:54:41.115035", "lvl": "target", "msg": "[    4.040337] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2024-12-09T09:54:41.115407", "lvl": "target", "msg": "[    4.040831] optee driver R-Car Rev.1.0.11"}
- {"dt": "2024-12-09T09:54:41.115694", "lvl": "target", "msg": "[    4.049179] optee: initialized driver"}
- {"dt": "2024-12-09T09:54:41.116021", "lvl": "target", "msg": "[    4.053158] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-09T09:54:41.116292", "lvl": "target", "msg": "[    4.068770] rcar_sound ec500000.sound: probed"}
- {"dt": "2024-12-09T09:54:41.116605", "lvl": "target", "msg": "[    4.073360] drop_monitor: Initializing network drop monitor service"}
- {"dt": "2024-12-09T09:54:41.116933", "lvl": "target", "msg": "[    4.079738] NET: Registered protocol family 17"}
- {"dt": "2024-12-09T09:54:41.117213", "lvl": "target", "msg": "[    4.084275] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-12-09T09:54:41.118309", "lvl": "target", "msg": "[    4.088562] Key type dns_resolver registered"}
- {"dt": "2024-12-09T09:54:41.135849", "lvl": "target", "msg": "[    4.093258] registered taskstats version 1"}
- {"dt": "2024-12-09T09:54:41.138567", "lvl": "target", "msg": "[    4.097339] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-09T09:54:41.182290", "lvl": "target", "msg": "[    4.112774] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2024-12-09T09:54:41.182765", "lvl": "target", "msg": "[    4.118686] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2024-12-09T09:54:41.183095", "lvl": "target", "msg": "[    4.123885] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2024-12-09T09:54:41.184094", "lvl": "target", "msg": "[    4.129503] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2024-12-09T09:54:41.184391", "lvl": "target", "msg": "[    4.137866] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-12-09T09:54:41.184657", "lvl": "target", "msg": "[    4.144455] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-12-09T09:54:41.185727", "lvl": "target", "msg": "[    4.151383] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2024-12-09T09:54:41.200849", "lvl": "target", "msg": "[    4.159095] [drm] Device feb00000.display probed"}
- {"dt": "2024-12-09T09:54:41.558901", "lvl": "target", "msg": "[    4.489541] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-09T09:54:41.559454", "lvl": "target", "msg": "[    4.511973] rcar-du feb00000.display: fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2024-12-09T09:54:41.562136", "lvl": "target", "msg": "[    4.520020] bd9571mwv 7-0030: Device: BD9571MWV rev. 4"}
- {"dt": "2024-12-09T09:54:41.621063", "lvl": "target", "msg": "[    4.577245] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz"}
- {"dt": "2024-12-09T09:54:41.700162", "lvl": "target", "msg": "[    4.634754] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 200 MHz"}
- {"dt": "2024-12-09T09:54:41.700727", "lvl": "target", "msg": "[    4.644962] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: sink widget TX overwritten"}
- {"dt": "2024-12-09T09:54:41.701082", "lvl": "target", "msg": "[    4.652926] asoc-audio-graph-card sound: ak4613-hifi <-> rsnd-dai.0 mapping ok"}
- {"dt": "2024-12-09T09:54:41.702089", "lvl": "target", "msg": "[    4.660321] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.1 mapping ok"}
- {"dt": "2024-12-09T09:54:41.702380", "lvl": "target", "msg": "[    4.667370] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.2 mapping ok"}
- {"dt": "2024-12-09T09:54:41.724756", "lvl": "target", "msg": "[    4.674377] asoc-audio-graph-card sound: ASoC: no DMI vendor name!"}
- {"dt": "2024-12-09T09:54:41.727832", "lvl": "target", "msg": "[    4.684484] hctosys: unable to open rtc device (rtc0)"}
- {"dt": "2024-12-09T09:54:41.816802", "lvl": "target", "msg": "[    4.764000] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=e6800000.ethernet-ffffffff:00, irq=183)"}
- {"dt": "2024-12-09T09:54:52.432106", "lvl": "target", "msg": "[   15.385045] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-09T09:54:54.917888", "lvl": "target", "msg": "[   15.416863] Sending DHCP requests .., OK"}
- {"dt": "2024-12-09T09:54:54.918441", "lvl": "target", "msg": "[   17.852772] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2024-12-09T09:54:54.918763", "lvl": "target", "msg": "[   17.860569] IP-Config: Complete:"}
- {"dt": "2024-12-09T09:54:54.919763", "lvl": "target", "msg": "[   17.863783]      device=eth0, hwaddr=00:80:45:1b:40:7f, ipaddr=192.168.66.11, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-12-09T09:54:54.920056", "lvl": "target", "msg": "[   17.873993]      host=192.168.66.11, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-09T09:54:54.920317", "lvl": "target", "msg": "[   17.880832]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2024-12-09T09:54:54.921323", "lvl": "target", "msg": "[   17.880833]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-09T09:54:54.951944", "lvl": "target", "msg": "[   17.890980] ALSA device list:"}
- {"dt": "2024-12-09T09:54:54.952511", "lvl": "target", "msg": "[   17.893936]   #0: rcar-sound"}
- {"dt": "2024-12-09T09:54:54.952857", "lvl": "target", "msg": "[   17.900199] Freeing unused kernel memory: 6080K"}
- {"dt": "2024-12-09T09:54:54.955162", "lvl": "target", "msg": "[   17.908971] Run /init as init process"}
- {"dt": "2024-12-09T09:54:54.971025", "lvl": "target", "msg": "Starting logging: OK"}
- {"dt": "2024-12-09T09:54:55.015722", "lvl": "target", "msg": "Populating /dev using udev: [   17.943269] udevd[317]: starting version 3.2.5"}
- {"dt": "2024-12-09T09:54:55.016268", "lvl": "target", "msg": "[   17.947994] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-09T09:54:55.016577", "lvl": "target", "msg": "[   17.954530] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-09T09:54:55.016903", "lvl": "target", "msg": "[   17.960990] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-09T09:54:55.019263", "lvl": "target", "msg": "[   17.971197] udevd[318]: starting eudev-3.2.5"}
- {"dt": "2024-12-09T09:54:55.258309", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-09T09:54:55.258863", "lvl": "target", "msg": "Initializing random number generator... done."}
- {"dt": "2024-12-09T09:54:55.274267", "lvl": "target", "msg": "Starting network: ip: RTNETLINK answers: File exists"}
- {"dt": "2024-12-09T09:54:55.274829", "lvl": "target", "msg": "FAIL"}
- {"dt": "2024-12-09T09:54:55.306105", "lvl": "target", "msg": "Starting dropbear sshd: OK"}
- {"dt": "2024-12-09T09:54:55.306667", "lvl": "target", "msg": "/bin/sh: can't access tty; job control turned off"}
- {"dt": "2024-12-09T09:54:55.307684", "lvl": "debug", "msg": "Matched prompt #12: / #"}
- {"dt": "2024-12-09T09:54:55.308046", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "17.21", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:55.308619", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2024-12-09T09:54:55.309061", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:17) [common]"}
- {"dt": "2024-12-09T09:54:55.309361", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "17.21", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:55.309946", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:19) [common]"}
- {"dt": "2024-12-09T09:54:55.310302", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:48) [common]"}
- {"dt": "2024-12-09T09:54:55.310682", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:48) [common]"}
- {"dt": "2024-12-09T09:54:55.311054", "lvl": "info", "msg": "start: 3 finalize (timeout 00:30:00) [common]"}
- {"dt": "2024-12-09T09:54:55.311432", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2024-12-09T09:54:55.311980", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2024-12-09T09:54:55.424832", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-09T09:54:55.444032", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-12-09T09:54:55.544997", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-12-09T09:54:55.545614", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "0.23", "extra": {"status": "success"}, "level": "3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-09T09:54:55.546319", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2024-12-09T09:54:56.548229", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-09T09:54:56.548946", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-09T09:54:56.549327", "lvl": "target", "msg": "/ # "}
- {"dt": "2024-12-09T09:54:56.650233", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:56.650889", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-12-09T09:54:56.651376", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-09T09:54:56.651845", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/ramdisk"}
- {"dt": "2024-12-09T09:54:56.654415", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/kernel"}
- {"dt": "2024-12-09T09:54:56.657946", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38249/tftp-deploy-nfnvbmzx/dtb"}
- {"dt": "2024-12-09T09:54:56.658261", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38249"}
- {"dt": "2024-12-09T09:54:56.667671", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38249"}
- {"dt": "2024-12-09T09:54:56.667873", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-09T09:54:56.667953", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
