- {"dt": "2024-12-08T09:53:52.773541", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-08T09:53:52.773804", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-08T09:53:52.773963", "lvl": "info", "msg": "Start time: 2024-12-08 09:53:52.773954+00:00 (UTC)"}
- {"dt": "2024-12-08T09:53:52.774142", "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-08T09:53:54.125221", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true exists"}
- {"dt": "2024-12-08T09:53:56.302419", "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-08T09:53:56.879327", "lvl": "info", "msg": "validate duration: 4.11"}
- {"dt": "2024-12-08T09:53:56.879489", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-08T09:53:56.879698", "lvl": "info", "msg": "start: 1 tftp-deploy (timeout 01:30:00) [common]"}
- {"dt": "2024-12-08T09:53:56.879818", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2024-12-08T09:53:56.879917", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2024-12-08T09:53:56.880090", "lvl": "debug", "msg": "Not decompressing ramdisk as can be used compressed."}
- {"dt": "2024-12-08T09:53:56.880197", "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-08T09:53:56.880270", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/ramdisk/rootfs.cpio.gz"}
- {"dt": "2024-12-08T09:53:56.880345", "lvl": "debug", "msg": "total size: 4145016 (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.294009", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.327940", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.336308", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.346545", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.350848", "lvl": "debug", "msg": "progress  20\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.356832", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.360337", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.362812", "lvl": "debug", "msg": "progress  35\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.364657", "lvl": "debug", "msg": "progress  40\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.366542", "lvl": "debug", "msg": "progress  45\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.371211", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.373126", "lvl": "debug", "msg": "progress  55\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.375239", "lvl": "debug", "msg": "progress  60\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.378434", "lvl": "debug", "msg": "progress  65\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.379654", "lvl": "debug", "msg": "progress  70\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.380948", "lvl": "debug", "msg": "progress  75\u202F% (2\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.382363", "lvl": "debug", "msg": "progress  80\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.383987", "lvl": "debug", "msg": "progress  85\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.385662", "lvl": "debug", "msg": "progress  90\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.387631", "lvl": "debug", "msg": "progress  95\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.389199", "lvl": "debug", "msg": "progress 100\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.389432", "lvl": "info", "msg": "3\u202FMB downloaded in 0.51\u202Fs (7.77\u202FMB/s)"}
- {"dt": "2024-12-08T09:53:57.389644", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:53:57.389736", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.51", "extra": {"label": "ramdisk", "md5sum": "4c89fd138cbd1f1cbbeea8f7aadd7364", "sha256sum": "cb162eee76c8bbf1987749dd6eb7f667c6dd8175a8550df2dd63e2fb1fd8cb05", "sha512sum": "5cdbe0e30431c92b62b86f06891a19b4c6074fdebba51f75fb9139839f14a9ab6a277a459e41619b469c4bfd9d5112bce53ebcf02dbbc2e96150acc68b23163b", "size": ! "4145016"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-08T09:53:57.389945", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:53:57.390061", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2024-12-08T09:53:57.390173", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2024-12-08T09:53:57.390417", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true"}
- {"dt": "2024-12-08T09:53:57.390511", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/kernel/Image"}
- {"dt": "2024-12-08T09:53:57.390593", "lvl": "debug", "msg": "total size: 31236608 (29\u202FMB)"}
- {"dt": "2024-12-08T09:53:57.390674", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-08T09:53:58.774683", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.787568", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.800999", "lvl": "debug", "msg": "progress  10\u202F% (3\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.814151", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.827618", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.841115", "lvl": "debug", "msg": "progress  25\u202F% (7\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.854281", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.867634", "lvl": "debug", "msg": "progress  35\u202F% (10\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.881068", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.894331", "lvl": "debug", "msg": "progress  45\u202F% (13\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.907779", "lvl": "debug", "msg": "progress  50\u202F% (14\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.921202", "lvl": "debug", "msg": "progress  55\u202F% (16\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.934362", "lvl": "debug", "msg": "progress  60\u202F% (17\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.947796", "lvl": "debug", "msg": "progress  65\u202F% (19\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.961209", "lvl": "debug", "msg": "progress  70\u202F% (20\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.974333", "lvl": "debug", "msg": "progress  75\u202F% (22\u202FMB)"}
- {"dt": "2024-12-08T09:53:58.987872", "lvl": "debug", "msg": "progress  80\u202F% (23\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.001272", "lvl": "debug", "msg": "progress  85\u202F% (25\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.014421", "lvl": "debug", "msg": "progress  90\u202F% (26\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.027854", "lvl": "debug", "msg": "progress  95\u202F% (28\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.041161", "lvl": "debug", "msg": "progress 100\u202F% (29\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.041367", "lvl": "info", "msg": "29\u202FMB downloaded in 1.65\u202Fs (18.05\u202FMB/s)"}
- {"dt": "2024-12-08T09:53:59.041552", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-12-08T09:53:59.041630", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.65", "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-08T09:53:59.041821", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-12-08T09:53:59.041916", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.042005", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.042202", "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-08T09:53:59.042278", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-08T09:53:59.042341", "lvl": "debug", "msg": "total size: 81477 (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.042407", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-08T09:53:59.235653", "lvl": "debug", "msg": "progress  40\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.236100", "lvl": "debug", "msg": "progress  80\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.236465", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-08T09:53:59.236702", "lvl": "info", "msg": "0\u202FMB downloaded in 0.19\u202Fs (0.40\u202FMB/s)"}
- {"dt": "2024-12-08T09:53:59.236937", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.237046", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.19", "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-08T09:53:59.237299", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.237420", "lvl": "debug", "msg": "start: 1.4 prepare-tftp-overlay (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.237539", "lvl": "debug", "msg": "start: 1.4.1 extract-nfsrootfs (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.237648", "lvl": "debug", "msg": "end: 1.4.1 extract-nfsrootfs (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.237758", "lvl": "debug", "msg": "start: 1.4.2 lava-overlay (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.237856", "lvl": "info", "msg": "[common] skipped lava-overlay - no test action."}
- {"dt": "2024-12-08T09:53:59.237961", "lvl": "debug", "msg": "end: 1.4.2 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.238069", "lvl": "debug", "msg": "start: 1.4.3 extract-overlay-ramdisk (timeout 01:29:58) [common]"}
- {"dt": "2024-12-08T09:53:59.399442", "lvl": "debug", "msg": "end: 1.4.3 extract-overlay-ramdisk (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.399661", "lvl": "debug", "msg": "start: 1.4.4 extract-modules (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.399809", "lvl": "debug", "msg": "end: 1.4.4 extract-modules (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.399928", "lvl": "debug", "msg": "start: 1.4.5 apply-overlay-tftp (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.400051", "lvl": "debug", "msg": "end: 1.4.5 apply-overlay-tftp (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.400155", "lvl": "debug", "msg": "start: 1.4.6 prepare-kernel (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.400257", "lvl": "debug", "msg": "start: 1.4.6.1 uboot-prepare-kernel (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.400361", "lvl": "debug", "msg": "end: 1.4.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.400456", "lvl": "debug", "msg": "end: 1.4.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.400552", "lvl": "debug", "msg": "start: 1.4.7 configure-preseed-file (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.400650", "lvl": "debug", "msg": "end: 1.4.7 configure-preseed-file (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:53:59.400755", "lvl": "debug", "msg": "start: 1.4.8 compress-ramdisk (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:53:59.400847", "lvl": "info", "msg": "Building ramdisk /var/lib/lava/dispatcher/tmp/38218/extract-overlay-ramdisk-2k8oyadt/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/38218/extract-overlay-ramdisk-2k8oyadt/ramdisk"}
- {"dt": "2024-12-08T09:53:59.500124", "lvl": "debug", "msg": ">> 26070 blocks\n"}
- {"dt": "2024-12-08T09:54:00.260899", "lvl": "debug", "msg": "Adding RAMdisk u-boot header."}
- {"dt": "2024-12-08T09:54:00.261099", "lvl": "debug", "msg": "mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/38218/extract-overlay-ramdisk-2k8oyadt/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/38218/extract-overlay-ramdisk-2k8oyadt/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:00.301253", "lvl": "debug", "msg": "output: Image Name:   "}
- {"dt": "2024-12-08T09:54:00.301422", "lvl": "debug", "msg": "output: Created:      Sun Dec  8 09:54:00 2024"}
- {"dt": "2024-12-08T09:54:00.301519", "lvl": "debug", "msg": "output: Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-12-08T09:54:00.301600", "lvl": "debug", "msg": "output: Data Size:    4199356 Bytes = 4100.93 KiB = 4.00 MiB"}
- {"dt": "2024-12-08T09:54:00.301675", "lvl": "debug", "msg": "output: Load Address: 00000000"}
- {"dt": "2024-12-08T09:54:00.301747", "lvl": "debug", "msg": "output: Entry Point:  00000000"}
- {"dt": "2024-12-08T09:54:00.301821", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-08T09:54:00.301988", "lvl": "debug", "msg": "rename /var/lib/lava/dispatcher/tmp/38218/extract-overlay-ramdisk-2k8oyadt/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:00.302152", "lvl": "debug", "msg": "end: 1.4.8 compress-ramdisk (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:00.302267", "lvl": "debug", "msg": "end: 1.4 prepare-tftp-overlay (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:00.302376", "lvl": "debug", "msg": "start: 1.5 lxc-create-udev-rule-action (timeout 01:29:57) [common]"}
- {"dt": "2024-12-08T09:54:00.302470", "lvl": "debug", "msg": "No LXC device requested"}
- {"dt": "2024-12-08T09:54:00.302569", "lvl": "debug", "msg": "end: 1.5 lxc-create-udev-rule-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:54:00.302649", "lvl": "debug", "msg": "Checking files for TFTP limit of 4294967296 bytes."}
- {"dt": "2024-12-08T09:54:00.303262", "lvl": "info", "msg": "end: 1 tftp-deploy (duration 00:00:03) [common]"}
- {"dt": "2024-12-08T09:54:00.303390", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.303498", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.303598", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:54:00.303698", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.303867", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-12-08T09:54:00.303953", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-08T09:54:00.304039", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2024-12-08T09:54:00.304116", "lvl": "debug", "msg": "- {DTB}: 38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-08T09:54:00.304194", "lvl": "debug", "msg": "- {INITRD}: 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:00.304270", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2024-12-08T09:54:00.304344", "lvl": "debug", "msg": "- {KERNEL}: 38218/tftp-deploy-vnhp7o5a/kernel/Image"}
- {"dt": "2024-12-08T09:54:00.304419", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-12-08T09:54:00.304504", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-12-08T09:54:00.304580", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-12-08T09:54:00.304654", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2024-12-08T09:54:00.304726", "lvl": "debug", "msg": "- {RAMDISK}: 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:00.304809", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-12-08T09:54:00.304883", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-12-08T09:54:00.304956", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-12-08T09:54:00.305029", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-12-08T09:54:00.305103", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-12-08T09:54:00.305177", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-12-08T09:54:00.305248", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-12-08T09:54:00.305322", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:00.305395", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:00.305467", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-12-08T09:54:00.305539", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-12-08T09:54:00.305611", "lvl": "info", "msg": "- tftp 0x48080000 38218/tftp-deploy-vnhp7o5a/kernel/Image"}
- {"dt": "2024-12-08T09:54:00.305684", "lvl": "info", "msg": "- tftp 0x4c000000 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:00.305758", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-12-08T09:54:00.305831", "lvl": "info", "msg": "- tftp 0x48000000 38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-08T09:54:00.305905", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-08T09:54:00.305978", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-08T09:54:00.306076", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:54:00.306160", "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-08T09:54:00.306368", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.306451", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2024-12-08T09:54:00.310395", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-08T09:54:00.310843", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:54:00.311008", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.311144", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.311256", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:20:00) [common]"}
- {"dt": "2024-12-08T09:54:00.311494", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2024-12-08T09:54:00.418463", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-08T09:54:01.484848", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-08T09:54:01.491352", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-08T09:54:01.591914", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:01.592146", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "1.28", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-08T09:54:01.592463", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:01.592637", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:59) [common]"}
- {"dt": "2024-12-08T09:54:01.592782", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-12-08T09:54:01.592903", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2024-12-08T09:54:01.593244", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-08T09:54:01.593364", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-08T09:54:01.593456", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-08T09:54:01.744564", "lvl": "target", "msg": "\0[    0.000149] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2024-12-08T09:54:01.744792", "lvl": "target", "msg": "[    0.004584] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2024-12-08T09:54:01.744899", "lvl": "target", "msg": "[    0.010118] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-12-08T09:54:01.744983", "lvl": "target", "msg": "[    0.014780] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2024-12-08T09:54:01.745063", "lvl": "target", "msg": "[    0.019552] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2024-12-08T09:54:01.745141", "lvl": "target", "msg": "[    0.024721] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-12-08T09:54:01.745216", "lvl": "target", "msg": "[    0.028762] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-12-08T09:54:01.745291", "lvl": "target", "msg": "[    0.034737] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:01.748384", "lvl": "target", "msg": "[    0.040599] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:01.813434", "lvl": "target", "msg": "[    0.046474] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:01.813644", "lvl": "target", "msg": "[    0.052349] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:01.813740", "lvl": "target", "msg": "[    0.058266] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2024-12-08T09:54:01.813824", "lvl": "target", "msg": "[    0.073578] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-12-08T09:54:01.813900", "lvl": "target", "msg": "[    0.083112] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-12-08T09:54:01.813974", "lvl": "target", "msg": "[    0.086411] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-12-08T09:54:01.814049", "lvl": "target", "msg": "[    0.091854] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-12-08T09:54:01.816570", "lvl": "target", "msg": "[    0.097211] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-12-08T09:54:01.856570", "lvl": "target", "msg": "[    0.114677] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2024-12-08T09:54:01.856792", "lvl": "target", "msg": "[    0.118321] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2024-12-08T09:54:01.856890", "lvl": "target", "msg": "[    0.123509] NOTICE:  BL2: Normal boot"}
- {"dt": "2024-12-08T09:54:01.856979", "lvl": "target", "msg": "[    0.127144] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2024-12-08T09:54:01.857057", "lvl": "target", "msg": "[    0.133691] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2024-12-08T09:54:01.857133", "lvl": "target", "msg": "[    0.139807] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2024-12-08T09:54:01.859763", "lvl": "target", "msg": "[    0.147407] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2024-12-08T09:54:01.875318", "lvl": "target", "msg": "[    0.156384] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2024-12-08T09:54:01.907285", "lvl": "target", "msg": "[    0.187315] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2024-12-08T09:54:01.923159", "lvl": "target", "msg": "[    0.216022] NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-12-08T09:54:02.160486", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:02.160703", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:02.160814", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2024-12-08T09:54:02.160898", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:02.160976", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-12-08T09:54:02.163628", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-08T09:54:02.530566", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-12-08T09:54:02.530769", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-12-08T09:54:02.530873", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:02.533725", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:02.533938", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-08T09:54:02.534049", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:02.565431", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2024-12-08T09:54:02.895454", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-12-08T09:54:02.895638", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-12-08T09:54:02.895731", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-12-08T09:54:02.898545", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-12-08T09:54:02.946370", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-12-08T09:54:02.946699", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-08T09:54:02.996894", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2024-12-08T09:54:02.997163", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:02.997344", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:57) [common]"}
- {"dt": "2024-12-08T09:54:02.997464", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2024-12-08T09:54:02.997580", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:57)"}
- {"dt": "2024-12-08T09:54:03.010160", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2024-12-08T09:54:03.010484", "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-08T09:54:03.010577", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-12-08T09:54:03.110877", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2024-12-08T09:54:03.111143", "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-08T09:54:03.121841", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-12-08T09:54:03.122135", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2024-12-08T09:54:03.222426", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:03.222633", "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-08T09:54:03.233508", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:03.233786", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2024-12-08T09:54:03.334070", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:03.334284", "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-08T09:54:03.345212", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-08T09:54:03.345492", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-12-08T09:54:03.445853", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2024-12-08T09:54:03.446207", "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-08T09:54:03.468784", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-12-08T09:54:09.787032", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2024-12-08T09:54:09.803056", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-12-08T09:54:10.042774", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-12-08T09:54:10.554436", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-12-08T09:54:11.066258", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1275 ms)"}
- {"dt": "2024-12-08T09:54:11.067077", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-12-08T09:54:11.168033", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2024-12-08T09:54:11.168810", "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-08T09:54:11.178093", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-12-08T09:54:11.178897", "lvl": "input", "msg": "tftp 0x48080000 38218/tftp-deploy-vnhp7o5a/kernel/Image\n"}
- {"dt": "2024-12-08T09:54:11.279744", "lvl": "target", "msg": "=> tftp 0x48080000 38218/tftp-deploy-vnhp7o5a/kernel/Image"}
- {"dt": "2024-12-08T09:54:11.280472", "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-08T09:54:11.290016", "lvl": "target", "msg": "tftp 0x48080000 38218/tftp-deploy-vnhp7o5a/kernel/Image"}
- {"dt": "2024-12-08T09:54:11.333042", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-08T09:54:11.333611", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-08T09:54:11.333993", "lvl": "target", "msg": "Filename '38218/tftp-deploy-vnhp7o5a/kernel/Image'."}
- {"dt": "2024-12-08T09:54:11.334337", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2024-12-08T09:54:11.661404", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-12-08T09:54:11.997114", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:12.332399", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:12.668262", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:13.003899", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:13.339816", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:13.691700", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:14.027529", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:14.379127", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:14.715120", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:15.066919", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:15.401790", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:15.754595", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:16.089391", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:16.441264", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:16.777126", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:17.112860", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:17.464343", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:17.816612", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:18.152266", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:18.488107", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:18.823861", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:19.175818", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:19.511672", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:19.847417", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:20.199697", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:20.535404", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:20.887235", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:21.238984", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:21.574808", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:21.926470", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:22.294303", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:22.550121", "lvl": "target", "msg": "\t ################################################"}
- {"dt": "2024-12-08T09:54:22.550655", "lvl": "target", "msg": "\t 2.7 MiB/s"}
- {"dt": "2024-12-08T09:54:22.550981", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-08T09:54:22.566147", "lvl": "target", "msg": "Bytes transferred = 31236608 (1dca200 hex)"}
- {"dt": "2024-12-08T09:54:22.567030", "lvl": "input", "msg": "tftp 0x4c000000 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot\n"}
- {"dt": "2024-12-08T09:54:22.667657", "lvl": "target", "msg": "=> tftp 0x4c000000 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:22.668335", "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:38)"}
- {"dt": "2024-12-08T09:54:22.674254", "lvl": "target", "msg": "tftp 0x4c000000 38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-12-08T09:54:22.717089", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-08T09:54:22.717569", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-08T09:54:22.717911", "lvl": "target", "msg": "Filename '38218/tftp-deploy-vnhp7o5a/ramdisk/ramdisk.cpio.gz.uboot'."}
- {"dt": "2024-12-08T09:54:22.718206", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2024-12-08T09:54:23.064315", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-12-08T09:54:23.416092", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:23.767653", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:24.119327", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-08T09:54:24.266812", "lvl": "target", "msg": "\t ###########################"}
- {"dt": "2024-12-08T09:54:24.267486", "lvl": "target", "msg": "\t 2.6 MiB/s"}
- {"dt": "2024-12-08T09:54:24.267868", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-08T09:54:24.268155", "lvl": "target", "msg": "Bytes transferred = 4199420 (4013fc hex)"}
- {"dt": "2024-12-08T09:54:24.269438", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-12-08T09:54:24.370159", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2024-12-08T09:54:24.370798", "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:36)"}
- {"dt": "2024-12-08T09:54:24.381043", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-12-08T09:54:24.381760", "lvl": "input", "msg": "tftp 0x48000000 38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb\n"}
- {"dt": "2024-12-08T09:54:24.482403", "lvl": "target", "msg": "=> tftp 0x48000000 38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-08T09:54:24.483027", "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:36)"}
- {"dt": "2024-12-08T09:54:24.488155", "lvl": "target", "msg": "tftp 0x48000000 38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-12-08T09:54:24.533066", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-08T09:54:24.533555", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-08T09:54:24.535115", "lvl": "target", "msg": "Filename '38218/tftp-deploy-vnhp7o5a/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb'."}
- {"dt": "2024-12-08T09:54:24.535503", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2024-12-08T09:54:24.573080", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2024-12-08T09:54:24.573524", "lvl": "target", "msg": "\t 2.2 MiB/s"}
- {"dt": "2024-12-08T09:54:24.573829", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-08T09:54:24.574099", "lvl": "target", "msg": "Bytes transferred = 81477 (13e45 hex)"}
- {"dt": "2024-12-08T09:54:24.574624", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'\n"}
- {"dt": "2024-12-08T09:54:24.675221", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-08T09:54:24.675965", "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:36)"}
- {"dt": "2024-12-08T09:54:24.681081", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-12-08T09:54:24.681803", "lvl": "input", "msg": "booti 0x48080000 0x4c000000 0x48000000\n"}
- {"dt": "2024-12-08T09:54:24.782444", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-08T09:54:24.783063", "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-08T09:54:24.783521", "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:36)"}
- {"dt": "2024-12-08T09:54:24.821554", "lvl": "target", "msg": "booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-12-08T09:54:24.822013", "lvl": "target", "msg": "## Loading init Ramdisk from Legacy Image at 4c000000 ..."}
- {"dt": "2024-12-08T09:54:24.865091", "lvl": "target", "msg": "   Image Name:   "}
- {"dt": "2024-12-08T09:54:24.865535", "lvl": "target", "msg": "   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-12-08T09:54:24.865851", "lvl": "target", "msg": "   Data Size:    4199356 Bytes = 4 MiB"}
- {"dt": "2024-12-08T09:54:24.866122", "lvl": "target", "msg": "   Load Address: 00000000"}
- {"dt": "2024-12-08T09:54:24.866374", "lvl": "target", "msg": "   Entry Point:  00000000"}
- {"dt": "2024-12-08T09:54:24.866624", "lvl": "target", "msg": "   Verifying Checksum ... OK"}
- {"dt": "2024-12-08T09:54:24.866870", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2024-12-08T09:54:24.867113", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2024-12-08T09:54:24.867352", "lvl": "target", "msg": "   Loading Ramdisk to bfa03000, end bfe043bc ... OK"}
- {"dt": "2024-12-08T09:54:24.877510", "lvl": "target", "msg": "   Loading Device Tree to 00000000bf9ec000, end 00000000bfa02e44 ... OK"}
- {"dt": "2024-12-08T09:54:24.877958", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:24.880605", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-12-08T09:54:24.881054", "lvl": "target", "msg": ""}
- {"dt": "2024-12-08T09:54:24.881805", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:22) [common]"}
- {"dt": "2024-12-08T09:54:24.882237", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:35) [common]"}
- {"dt": "2024-12-08T09:54:24.882555", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-08T09:54:24.882858", "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-08T09:54:24.883164", "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-08T09:54:26.510418", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2024-12-08T09:54:26.510971", "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-08T09:54:26.511282", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-08T09:54:26.511559", "lvl": "target", "msg": "[    0.000000] efi: Getting EFI parameters from FDT:"}
- {"dt": "2024-12-08T09:54:26.511811", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-08T09:54:26.512549", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:34) [common]"}
- {"dt": "2024-12-08T09:54:26.512967", "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-08T09:54:26.513263", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-08T09:54:26.513594", "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-08T09:54:26.513896", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-08T09:54:26.514152", "lvl": "debug", "msg": "No login prompt set."}
- {"dt": "2024-12-08T09:54:26.514424", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-08T09:54:26.514662", "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-08T09:54:26.515188", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:34)"}
- {"dt": "2024-12-08T09:54:26.516018", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2024-12-08T09:54:26.553336", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-08T09:54:26.553905", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2024-12-08T09:54:26.554219", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-08T09:54:26.554495", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2024-12-08T09:54:26.556970", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-08T09:54:26.596315", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-12-08T09:54:26.596907", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-08T09:54:26.597229", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efe3800-0x77efe4fff]"}
- {"dt": "2024-12-08T09:54:26.597509", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-08T09:54:26.597764", "lvl": "target", "msg": "[    0.000000]   DMA32    [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2024-12-08T09:54:26.598012", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2024-12-08T09:54:26.598255", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-08T09:54:26.598869", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-08T09:54:26.639319", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2024-12-08T09:54:26.639870", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2024-12-08T09:54:26.640179", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2024-12-08T09:54:26.640448", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2024-12-08T09:54:26.640705", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2024-12-08T09:54:26.640989", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-08T09:54:26.642522", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-08T09:54:26.682258", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-08T09:54:26.682804", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-08T09:54:26.683113", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2024-12-08T09:54:26.683381", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2024-12-08T09:54:26.683635", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 31 pages/cpu s86744 r8192 d32040 u126976"}
- {"dt": "2024-12-08T09:54:26.683882", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-08T09:54:26.684127", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-12-08T09:54:26.684369", "lvl": "target", "msg": "[    0.000000] Speculative Store Bypass Disable mitigation not required"}
- {"dt": "2024-12-08T09:54:26.725163", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2020032"}
- {"dt": "2024-12-08T09:54:26.725707", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-12-08T09:54:26.726014", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp"}
- {"dt": "2024-12-08T09:54:26.726287", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2024-12-08T09:54:26.726970", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-12-08T09:54:26.727252", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2024-12-08T09:54:26.728849", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0xbb9ec000-0xbf9ec000] (64MB)"}
- {"dt": "2024-12-08T09:54:26.768101", "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-08T09:54:26.768649", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2024-12-08T09:54:26.769001", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-08T09:54:26.769277", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2024-12-08T09:54:26.769534", "lvl": "target", "msg": "[    0.000000] \tTasks RCU enabled."}
- {"dt": "2024-12-08T09:54:26.771524", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-08T09:54:26.811129", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2024-12-08T09:54:26.811646", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-08T09:54:26.811951", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2024-12-08T09:54:26.812218", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-08T09:54:26.812470", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x318/0x4ac with crng_init=0"}
- {"dt": "2024-12-08T09:54:26.812717", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2024-12-08T09:54:26.853881", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2024-12-08T09:54:26.854441", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2024-12-08T09:54:26.854752", "lvl": "target", "msg": "[    0.000137] Console: colour dummy device 80x25"}
- {"dt": "2024-12-08T09:54:26.855025", "lvl": "target", "msg": "[    0.000206] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2024-12-08T09:54:26.855284", "lvl": "target", "msg": "[    0.000214] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-08T09:54:26.855530", "lvl": "target", "msg": "[    0.000271] LSM: Security Framework initializing"}
- {"dt": "2024-12-08T09:54:26.855776", "lvl": "target", "msg": "[    0.000306] Smack:  Initializing."}
- {"dt": "2024-12-08T09:54:26.896814", "lvl": "target", "msg": "[    0.000309] Smack:  IPv6 port labeling enabled."}
- {"dt": "2024-12-08T09:54:26.897354", "lvl": "target", "msg": "[    0.000377] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-08T09:54:26.897664", "lvl": "target", "msg": "[    0.000423] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-08T09:54:26.897939", "lvl": "target", "msg": "[    0.023956] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-08T09:54:26.898197", "lvl": "target", "msg": "[    0.031945] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-08T09:54:26.898442", "lvl": "target", "msg": "[    0.041241] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2024-12-08T09:54:26.898686", "lvl": "target", "msg": "[    0.042869] EFI services will not be available."}
- {"dt": "2024-12-08T09:54:26.899517", "lvl": "target", "msg": "[    0.047972] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-08T09:54:26.939891", "lvl": "target", "msg": "[    0.080187] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-08T09:54:26.940458", "lvl": "target", "msg": "[    0.080225] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2024-12-08T09:54:26.940802", "lvl": "target", "msg": "[    0.112190] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-08T09:54:26.941079", "lvl": "target", "msg": "[    0.112211] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2024-12-08T09:54:26.941338", "lvl": "target", "msg": "[    0.144232] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-08T09:54:26.941583", "lvl": "target", "msg": "[    0.144252] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2024-12-08T09:54:26.941827", "lvl": "target", "msg": "[    0.176282] CPU features: detected: ARM erratum 845719"}
- {"dt": "2024-12-08T09:54:26.942070", "lvl": "target", "msg": "[    0.176293] Detected VIPT I-cache on CPU4"}
- {"dt": "2024-12-08T09:54:26.982943", "lvl": "target", "msg": "[    0.176332] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2024-12-08T09:54:26.983482", "lvl": "target", "msg": "[    0.208313] Detected VIPT I-cache on CPU5"}
- {"dt": "2024-12-08T09:54:26.983791", "lvl": "target", "msg": "[    0.208337] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2024-12-08T09:54:26.984057", "lvl": "target", "msg": "[    0.240361] Detected VIPT I-cache on CPU6"}
- {"dt": "2024-12-08T09:54:26.984311", "lvl": "target", "msg": "[    0.240384] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2024-12-08T09:54:26.984560", "lvl": "target", "msg": "[    0.272390] Detected VIPT I-cache on CPU7"}
- {"dt": "2024-12-08T09:54:26.984836", "lvl": "target", "msg": "[    0.272413] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2024-12-08T09:54:26.985078", "lvl": "target", "msg": "[    0.272489] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2024-12-08T09:54:26.986137", "lvl": "target", "msg": "[    0.272511] SMP: Total of 8 processors activated."}
- {"dt": "2024-12-08T09:54:27.025831", "lvl": "target", "msg": "[    0.272516] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-08T09:54:27.026396", "lvl": "target", "msg": "[    0.272520] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-08T09:54:27.026702", "lvl": "target", "msg": "[    0.282824] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-08T09:54:27.026976", "lvl": "target", "msg": "[    0.282856] alternatives: patching kernel code"}
- {"dt": "2024-12-08T09:54:27.027231", "lvl": "target", "msg": "[    0.283654] devtmpfs: initialized"}
- {"dt": "2024-12-08T09:54:27.027479", "lvl": "target", "msg": "[    0.290099] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-08T09:54:27.027727", "lvl": "target", "msg": "[    0.290113] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.028587", "lvl": "target", "msg": "[    0.299693] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-08T09:54:27.068695", "lvl": "target", "msg": "[    0.301076] DMI not present or invalid."}
- {"dt": "2024-12-08T09:54:27.069309", "lvl": "target", "msg": "[    0.301348] NET: Registered protocol family 16"}
- {"dt": "2024-12-08T09:54:27.069620", "lvl": "target", "msg": "[    0.302150] DMA: preallocated 256 KiB pool for atomic allocations"}
- {"dt": "2024-12-08T09:54:27.069894", "lvl": "target", "msg": "[    0.302164] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-08T09:54:27.070150", "lvl": "target", "msg": "[    0.302294] audit: type=2000 audit(0.300:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-08T09:54:27.070397", "lvl": "target", "msg": "[    0.304424] cpuidle: using governor menu"}
- {"dt": "2024-12-08T09:54:27.070638", "lvl": "target", "msg": "[    0.304631] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-08T09:54:27.071976", "lvl": "target", "msg": "[    0.305747] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-08T09:54:27.111714", "lvl": "target", "msg": "[    0.308400] sh-pfc e6060000.pin-controller: IRQ index 0 not found"}
- {"dt": "2024-12-08T09:54:27.112267", "lvl": "target", "msg": "[    0.308639] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered"}
- {"dt": "2024-12-08T09:54:27.112578", "lvl": "target", "msg": "[    0.330940] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-08T09:54:27.112889", "lvl": "target", "msg": "[    0.330949] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-08T09:54:27.113151", "lvl": "target", "msg": "[    0.330953] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-08T09:54:27.113399", "lvl": "target", "msg": "[    0.330957] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-08T09:54:27.114860", "lvl": "target", "msg": "[    0.332851] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-12-08T09:54:27.154694", "lvl": "target", "msg": "[    0.335595] ACPI: Interpreter disabled."}
- {"dt": "2024-12-08T09:54:27.155260", "lvl": "target", "msg": "[    0.338514] rcar-avs: use avs value: 0"}
- {"dt": "2024-12-08T09:54:27.155563", "lvl": "target", "msg": "[    0.338526] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2024-12-08T09:54:27.155834", "lvl": "target", "msg": "[    0.338533] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2024-12-08T09:54:27.156086", "lvl": "target", "msg": "[    0.338539] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2024-12-08T09:54:27.156332", "lvl": "target", "msg": "[    0.338546] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2024-12-08T09:54:27.156574", "lvl": "target", "msg": "[    0.338553] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2024-12-08T09:54:27.156851", "lvl": "target", "msg": "[    0.339618] iommu: Default domain type: Translated "}
- {"dt": "2024-12-08T09:54:27.197387", "lvl": "target", "msg": "[    0.340104] ipmmu-vmsa e67b0000.mmu: IPMMU context 0 is reserved"}
- {"dt": "2024-12-08T09:54:27.197948", "lvl": "target", "msg": "[    0.340777] vgaarb: loaded"}
- {"dt": "2024-12-08T09:54:27.198254", "lvl": "target", "msg": "[    0.340985] SCSI subsystem initialized"}
- {"dt": "2024-12-08T09:54:27.198526", "lvl": "target", "msg": "[    0.341255] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-08T09:54:27.198781", "lvl": "target", "msg": "[    0.341279] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-08T09:54:27.199027", "lvl": "target", "msg": "[    0.341331] usbcore: registered new device driver usb"}
- {"dt": "2024-12-08T09:54:27.199269", "lvl": "target", "msg": "[    0.342300] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2024-12-08T09:54:27.199513", "lvl": "target", "msg": "[    0.342595] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-08T09:54:27.200558", "lvl": "target", "msg": "[    0.342610] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-08T09:54:27.240648", "lvl": "target", "msg": "[    0.342657] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-08T09:54:27.241224", "lvl": "target", "msg": "[    0.342661] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-08T09:54:27.241539", "lvl": "target", "msg": "[    0.342669] PTP clock support registered"}
- {"dt": "2024-12-08T09:54:27.241812", "lvl": "target", "msg": "[    0.342800] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-12-08T09:54:27.242071", "lvl": "target", "msg": "[    0.344109] FPGA manager framework"}
- {"dt": "2024-12-08T09:54:27.242323", "lvl": "target", "msg": "[    0.344155] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-08T09:54:27.242570", "lvl": "target", "msg": "[    0.344440] NetLabel: Initializing"}
- {"dt": "2024-12-08T09:54:27.242813", "lvl": "target", "msg": "[    0.344445] NetLabel:  domain hash size = 128"}
- {"dt": "2024-12-08T09:54:27.243806", "lvl": "target", "msg": "[    0.344448] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-12-08T09:54:27.283605", "lvl": "target", "msg": "[    0.344484] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-12-08T09:54:27.284144", "lvl": "target", "msg": "[    0.344816] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-08T09:54:27.284452", "lvl": "target", "msg": "[    0.663331] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-08T09:54:27.284722", "lvl": "target", "msg": "[    0.663378] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-12-08T09:54:27.285021", "lvl": "target", "msg": "[    0.663522] pnp: PnP ACPI: disabled"}
- {"dt": "2024-12-08T09:54:27.285269", "lvl": "target", "msg": "[    0.667171] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-12-08T09:54:27.285518", "lvl": "target", "msg": "[    0.667953] NET: Registered protocol family 2"}
- {"dt": "2024-12-08T09:54:27.326536", "lvl": "target", "msg": "[    0.668217] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.327087", "lvl": "target", "msg": "[    0.668281] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.327419", "lvl": "target", "msg": "[    0.668562] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.327702", "lvl": "target", "msg": "[    0.669171] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-08T09:54:27.327960", "lvl": "target", "msg": "[    0.669306] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.328209", "lvl": "target", "msg": "[    0.669412] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-08T09:54:27.329641", "lvl": "target", "msg": "[    0.669637] NET: Registered protocol family 1"}
- {"dt": "2024-12-08T09:54:27.369507", "lvl": "target", "msg": "[    0.670009] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-08T09:54:27.370044", "lvl": "target", "msg": "[    0.670015] RPC: Registered udp transport module."}
- {"dt": "2024-12-08T09:54:27.370353", "lvl": "target", "msg": "[    0.670018] RPC: Registered tcp transport module."}
- {"dt": "2024-12-08T09:54:27.370623", "lvl": "target", "msg": "[    0.670022] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-08T09:54:27.370878", "lvl": "target", "msg": "[    0.670032] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-08T09:54:27.371128", "lvl": "target", "msg": "[    0.670143] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-08T09:54:27.371374", "lvl": "target", "msg": "[    0.809120] Freeing initrd memory: 4100K"}
- {"dt": "2024-12-08T09:54:27.372637", "lvl": "target", "msg": "[    0.809940] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2024-12-08T09:54:27.412495", "lvl": "target", "msg": "[    0.810193] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2024-12-08T09:54:27.413068", "lvl": "target", "msg": "[    0.810629] kvm [1]: IPA Size Limit: 40bits"}
- {"dt": "2024-12-08T09:54:27.413386", "lvl": "target", "msg": "[    0.811159] kvm [1]: vgic interrupt IRQ1"}
- {"dt": "2024-12-08T09:54:27.413658", "lvl": "target", "msg": "[    0.811309] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-12-08T09:54:27.413913", "lvl": "target", "msg": "[    0.842688] Initialise system trusted keyrings"}
- {"dt": "2024-12-08T09:54:27.414159", "lvl": "target", "msg": "[    0.842809] workingset: timestamp_bits=44 max_order=21 bucket_order=0"}
- {"dt": "2024-12-08T09:54:27.414403", "lvl": "target", "msg": "[    0.846119] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-12-08T09:54:27.415641", "lvl": "target", "msg": "[    0.846549] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-08T09:54:27.455408", "lvl": "target", "msg": "[    0.846567] Key type id_resolver registered"}
- {"dt": "2024-12-08T09:54:27.455940", "lvl": "target", "msg": "[    0.846571] Key type id_legacy registered"}
- {"dt": "2024-12-08T09:54:27.456252", "lvl": "target", "msg": "[    0.846580] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-08T09:54:27.456527", "lvl": "target", "msg": "[    0.846728] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-12-08T09:54:27.456818", "lvl": "target", "msg": "[    0.858199] Key type asymmetric registered"}
- {"dt": "2024-12-08T09:54:27.457073", "lvl": "target", "msg": "[    0.858206] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-08T09:54:27.457319", "lvl": "target", "msg": "[    0.858240] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2024-12-08T09:54:27.457563", "lvl": "target", "msg": "[    0.858246] io scheduler mq-deadline registered"}
- {"dt": "2024-12-08T09:54:27.458562", "lvl": "target", "msg": "[    0.858250] io scheduler kyber registered"}
- {"dt": "2024-12-08T09:54:27.498363", "lvl": "target", "msg": "[    0.870876] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-08T09:54:27.498915", "lvl": "target", "msg": "[    0.871090] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2024-12-08T09:54:27.499232", "lvl": "target", "msg": "[    0.871281] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2024-12-08T09:54:27.499508", "lvl": "target", "msg": "[    0.871468] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-08T09:54:27.499766", "lvl": "target", "msg": "[    0.871656] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2024-12-08T09:54:27.500015", "lvl": "target", "msg": "[    0.871850] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2024-12-08T09:54:27.500258", "lvl": "target", "msg": "[    0.872045] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2024-12-08T09:54:27.501280", "lvl": "target", "msg": "[    0.872227] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2024-12-08T09:54:27.541176", "lvl": "target", "msg": "[    0.873908] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2024-12-08T09:54:27.541708", "lvl": "target", "msg": "[    0.873932] rcar-pcie fe000000.pcie:    IO 0xfe100000..0xfe1fffff -> 0x00000000"}
- {"dt": "2024-12-08T09:54:27.542018", "lvl": "target", "msg": "[    0.873949] rcar-pcie fe000000.pcie:   MEM 0xfe200000..0xfe3fffff -> 0xfe200000"}
- {"dt": "2024-12-08T09:54:27.542292", "lvl": "target", "msg": "[    0.873962] rcar-pcie fe000000.pcie:   MEM 0x30000000..0x37ffffff -> 0x30000000"}
- {"dt": "2024-12-08T09:54:27.542549", "lvl": "target", "msg": "[    0.873971] rcar-pcie fe000000.pcie:   MEM 0x38000000..0x3fffffff -> 0x38000000"}
- {"dt": "2024-12-08T09:54:27.542803", "lvl": "target", "msg": "[    0.940440] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2024-12-08T09:54:27.584230", "lvl": "target", "msg": "[    0.940579] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2024-12-08T09:54:27.584790", "lvl": "target", "msg": "[    0.940597] rcar-pcie ee800000.pcie:    IO 0xee900000..0xee9fffff -> 0x00000000"}
- {"dt": "2024-12-08T09:54:27.585115", "lvl": "target", "msg": "[    0.940612] rcar-pcie ee800000.pcie:   MEM 0xeea00000..0xeebfffff -> 0xeea00000"}
- {"dt": "2024-12-08T09:54:27.585390", "lvl": "target", "msg": "[    0.940625] rcar-pcie ee800000.pcie:   MEM 0xc0000000..0xc7ffffff -> 0xc0000000"}
- {"dt": "2024-12-08T09:54:27.585646", "lvl": "target", "msg": "[    0.940634] rcar-pcie ee800000.pcie:   MEM 0xc8000000..0xcfffffff -> 0xc8000000"}
- {"dt": "2024-12-08T09:54:27.585896", "lvl": "target", "msg": "[    1.004444] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2024-12-08T09:54:27.586141", "lvl": "target", "msg": "[    1.006492] EINJ: ACPI disabled."}
- {"dt": "2024-12-08T09:54:27.627082", "lvl": "target", "msg": "[    1.030233] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-12-08T09:54:27.627625", "lvl": "target", "msg": "[    1.032237] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-12-08T09:54:27.627940", "lvl": "target", "msg": "[    1.032594] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 37, base_baud = 0) is a hscif"}
- {"dt": "2024-12-08T09:54:27.628236", "lvl": "target", "msg": "[    1.033017] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 38, base_baud = 0) is a hscif"}
- {"dt": "2024-12-08T09:54:27.628500", "lvl": "target", "msg": "[    1.033235] sh-pfc e6060000.pin-controller: pin GP_6_26 already requested by ee0a0200.usb-phy; cannot claim for e6560000.serial"}
- {"dt": "2024-12-08T09:54:27.630268", "lvl": "target", "msg": "[    1.033241] sh-pfc e6060000.pin-controller: pin-218 (e6560000.serial) status -22"}
- {"dt": "2024-12-08T09:54:27.670100", "lvl": "target", "msg": "[    1.033248] 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-08T09:54:27.670636", "lvl": "target", "msg": "[    1.033253] sh-sci e6560000.serial: Error applying setting, reverse things back"}
- {"dt": "2024-12-08T09:54:27.670939", "lvl": "target", "msg": "[    1.033263] sh-sci: probe of e6560000.serial failed with error -22"}
- {"dt": "2024-12-08T09:54:27.671211", "lvl": "target", "msg": "[    1.033493] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 127, base_baud = 0) is a scif"}
- {"dt": "2024-12-08T09:54:27.673225", "lvl": "target", "msg": "[    1.033834] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 128, base_baud = 0) is a scif"}
- {"dt": "2024-12-08T09:54:27.717851", "lvl": "target", "msg": "[    2.233095] printk: console [ttySC0] enabled"}
- {"dt": "2024-12-08T09:54:27.718376", "lvl": "target", "msg": "[    2.237674] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 129, base_baud = 0) is a scif"}
- {"dt": "2024-12-08T09:54:27.718685", "lvl": "target", "msg": "[    2.246803] msm_serial: driver initialized"}
- {"dt": "2024-12-08T09:54:27.718955", "lvl": "target", "msg": "[    2.253854] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-12-08T09:54:27.719213", "lvl": "target", "msg": "[    2.260443] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-12-08T09:54:27.719463", "lvl": "target", "msg": "[    2.266748] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-08T09:54:27.749765", "lvl": "target", "msg": "[    2.276609] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-08T09:54:27.750202", "lvl": "target", "msg": "[    2.284641] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-08T09:54:27.753046", "lvl": "target", "msg": "[    2.294444] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-08T09:54:27.753467", "lvl": "target", "msg": "[    2.307941] brd: module loaded"}
- {"dt": "2024-12-08T09:54:27.769294", "lvl": "target", "msg": "[    2.316452] loop: module loaded"}
- {"dt": "2024-12-08T09:54:27.874750", "lvl": "target", "msg": "[    2.394986] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2024-12-08T09:54:27.875290", "lvl": "target", "msg": "[    2.399210] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-12-08T09:54:27.875637", "lvl": "target", "msg": "[    2.405121] thunder_xcv, ver 1.0"}
- {"dt": "2024-12-08T09:54:27.875928", "lvl": "target", "msg": "[    2.408364] thunder_bgx, ver 1.0"}
- {"dt": "2024-12-08T09:54:27.876188", "lvl": "target", "msg": "[    2.411596] nicpf, ver 1.0"}
- {"dt": "2024-12-08T09:54:27.876442", "lvl": "target", "msg": "[    2.414955] hclge is initializing"}
- {"dt": "2024-12-08T09:54:27.876695", "lvl": "target", "msg": "[    2.418256] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-12-08T09:54:27.876994", "lvl": "target", "msg": "[    2.425441] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-12-08T09:54:27.878006", "lvl": "target", "msg": "[    2.430760] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k"}
- {"dt": "2024-12-08T09:54:27.921035", "lvl": "target", "msg": "[    2.436564] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-12-08T09:54:27.921530", "lvl": "target", "msg": "[    2.442471] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k"}
- {"dt": "2024-12-08T09:54:27.921876", "lvl": "target", "msg": "[    2.449398] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-12-08T09:54:27.922905", "lvl": "target", "msg": "[    2.454965] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k"}
- {"dt": "2024-12-08T09:54:27.923221", "lvl": "target", "msg": "[    2.462755] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-12-08T09:54:27.923486", "lvl": "target", "msg": "[    2.469011] sky2: driver version 1.30"}
- {"dt": "2024-12-08T09:54:27.923744", "lvl": "target", "msg": "[    2.473383] libphy: ravb_mii: probed"}
- {"dt": "2024-12-08T09:54:27.960852", "lvl": "target", "msg": "[    2.479425] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 122."}
- {"dt": "2024-12-08T09:54:27.961386", "lvl": "target", "msg": "[    2.489152] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-12-08T09:54:27.961721", "lvl": "target", "msg": "[    2.495810] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-12-08T09:54:27.962021", "lvl": "target", "msg": "[    2.502317] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-12-08T09:54:27.963287", "lvl": "target", "msg": "[    2.506770] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-12-08T09:54:28.002821", "lvl": "target", "msg": "[    2.536875] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.003342", "lvl": "target", "msg": "[    2.542532] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-08T09:54:28.005195", "lvl": "target", "msg": "[    2.550391] ehci-platform ee080100.usb: irq 175, io mem 0xee080100"}
- {"dt": "2024-12-08T09:54:28.051450", "lvl": "target", "msg": "[    2.568823] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-08T09:54:28.051929", "lvl": "target", "msg": "[    2.575325] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.052255", "lvl": "target", "msg": "[    2.579074] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.052530", "lvl": "target", "msg": "[    2.583256] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.052812", "lvl": "target", "msg": "[    2.588898] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-08T09:54:28.054785", "lvl": "target", "msg": "[    2.596744] ehci-platform ee0a0100.usb: irq 176, io mem 0xee0a0100"}
- {"dt": "2024-12-08T09:54:28.099456", "lvl": "target", "msg": "[    2.616825] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-08T09:54:28.099918", "lvl": "target", "msg": "[    2.623242] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.100240", "lvl": "target", "msg": "[    2.626988] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.100542", "lvl": "target", "msg": "[    2.631133] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.100845", "lvl": "target", "msg": "[    2.636774] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-08T09:54:28.101113", "lvl": "target", "msg": "[    2.644611] ehci-platform ee0c0100.usb: irq 177, io mem 0xee0c0100"}
- {"dt": "2024-12-08T09:54:28.126692", "lvl": "target", "msg": "[    2.664825] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-08T09:54:28.127229", "lvl": "target", "msg": "[    2.671214] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.129918", "lvl": "target", "msg": "[    2.674960] hub 3-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.168648", "lvl": "target", "msg": "[    2.704859] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.169196", "lvl": "target", "msg": "[    2.710501] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-08T09:54:28.171850", "lvl": "target", "msg": "[    2.718332] ehci-platform ee0e0100.usb: irq 178, io mem 0xee0e0100"}
- {"dt": "2024-12-08T09:54:28.215194", "lvl": "target", "msg": "[    2.736819] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-08T09:54:28.215729", "lvl": "target", "msg": "[    2.743241] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.216037", "lvl": "target", "msg": "[    2.746987] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.216308", "lvl": "target", "msg": "[    2.751137] ehci-orion: EHCI orion driver"}
- {"dt": "2024-12-08T09:54:28.216560", "lvl": "target", "msg": "[    2.755351] ehci-exynos: EHCI EXYNOS driver"}
- {"dt": "2024-12-08T09:54:28.216833", "lvl": "target", "msg": "[    2.759616] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-12-08T09:54:28.217081", "lvl": "target", "msg": "[    2.765781] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-12-08T09:54:28.217323", "lvl": "target", "msg": "[    2.770234] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-12-08T09:54:28.241366", "lvl": "target", "msg": "[    2.775594] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-08T09:54:28.241895", "lvl": "target", "msg": "[    2.782277] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2024-12-08T09:54:28.243525", "lvl": "target", "msg": "[    2.790128] ohci-platform ee080000.usb: irq 175, io mem 0xee080000"}
- {"dt": "2024-12-08T09:54:28.358528", "lvl": "target", "msg": "[    2.883686] hub 5-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.359058", "lvl": "target", "msg": "[    2.887438] hub 5-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.359362", "lvl": "target", "msg": "[    2.891564] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-08T09:54:28.359630", "lvl": "target", "msg": "[    2.898243] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2024-12-08T09:54:28.361634", "lvl": "target", "msg": "[    2.906094] ohci-platform ee0a0000.usb: irq 176, io mem 0xee0a0000"}
- {"dt": "2024-12-08T09:54:28.441484", "lvl": "target", "msg": "[    2.999675] hub 6-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.475212", "lvl": "target", "msg": "[    3.003421] hub 6-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.475746", "lvl": "target", "msg": "[    3.007554] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-08T09:54:28.476058", "lvl": "target", "msg": "[    3.014232] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2024-12-08T09:54:28.478378", "lvl": "target", "msg": "[    3.022071] ohci-platform ee0c0000.usb: irq 177, io mem 0xee0c0000"}
- {"dt": "2024-12-08T09:54:28.558459", "lvl": "target", "msg": "[    3.115655] hub 7-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.592307", "lvl": "target", "msg": "[    3.119405] hub 7-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.592903", "lvl": "target", "msg": "[    3.123521] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-08T09:54:28.593218", "lvl": "target", "msg": "[    3.130198] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2024-12-08T09:54:28.594753", "lvl": "target", "msg": "[    3.138026] ohci-platform ee0e0000.usb: irq 178, io mem 0xee0e0000"}
- {"dt": "2024-12-08T09:54:28.704186", "lvl": "target", "msg": "[    3.231660] hub 8-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.704732", "lvl": "target", "msg": "[    3.235405] hub 8-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.705094", "lvl": "target", "msg": "[    3.239537] ohci-exynos: OHCI EXYNOS driver"}
- {"dt": "2024-12-08T09:54:28.705365", "lvl": "target", "msg": "[    3.244327] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.706983", "lvl": "target", "msg": "[    3.249538] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2024-12-08T09:54:28.750730", "lvl": "target", "msg": "[    3.272753] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2024-12-08T09:54:28.751205", "lvl": "target", "msg": "[    3.281866] xhci-hcd ee000000.usb: irq 173, io mem 0xee000000"}
- {"dt": "2024-12-08T09:54:28.751525", "lvl": "target", "msg": "[    3.287985] hub 9-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.751800", "lvl": "target", "msg": "[    3.291733] hub 9-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.752055", "lvl": "target", "msg": "[    3.295777] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-08T09:54:28.752188", "lvl": "target", "msg": "[    3.300983] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2024-12-08T09:54:28.753992", "lvl": "target", "msg": "[    3.308433] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-08T09:54:28.796075", "lvl": "target", "msg": "[    3.314708] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2024-12-08T09:54:28.796551", "lvl": "target", "msg": "[    3.323090] hub 10-0:1.0: USB hub found"}
- {"dt": "2024-12-08T09:54:28.796906", "lvl": "target", "msg": "[    3.326922] hub 10-0:1.0: 1 port detected"}
- {"dt": "2024-12-08T09:54:28.797205", "lvl": "target", "msg": "[    3.331492] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-08T09:54:28.797478", "lvl": "target", "msg": "[    3.337527] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-08T09:54:28.797737", "lvl": "target", "msg": "[    3.344039] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-08T09:54:28.797988", "lvl": "target", "msg": "[    3.351557] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2024-12-08T09:54:28.854570", "lvl": "target", "msg": "[    3.357258] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2024-12-08T09:54:28.855100", "lvl": "target", "msg": "[    3.362436] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2024-12-08T09:54:28.855408", "lvl": "target", "msg": "[    3.367023] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2024-12-08T09:54:28.855682", "lvl": "target", "msg": "[    3.372710] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2024-12-08T09:54:28.855939", "lvl": "target", "msg": "[    3.377879] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2024-12-08T09:54:28.856186", "lvl": "target", "msg": "[    3.383129] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2024-12-08T09:54:28.856430", "lvl": "target", "msg": "[    3.390275] i2c /dev entries driver"}
- {"dt": "2024-12-08T09:54:28.856671", "lvl": "target", "msg": "[    3.403013] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2024-12-08T09:54:28.856949", "lvl": "target", "msg": "[    3.407229] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2024-12-08T09:54:28.857929", "lvl": "target", "msg": "[    3.411666] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2024-12-08T09:54:28.904950", "lvl": "target", "msg": "[    3.417665] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2024-12-08T09:54:28.905474", "lvl": "target", "msg": "[    3.422043] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2024-12-08T09:54:28.905782", "lvl": "target", "msg": "[    3.426594] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-08T09:54:28.906054", "lvl": "target", "msg": "[    3.433075] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2024-12-08T09:54:28.906310", "lvl": "target", "msg": "[    3.441998] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2024-12-08T09:54:28.908120", "lvl": "target", "msg": "[    3.452149] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2024-12-08T09:54:28.924104", "lvl": "target", "msg": "[    3.477153] random: fast init done"}
- {"dt": "2024-12-08T09:54:29.022930", "lvl": "target", "msg": "[    3.550670] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2024-12-08T09:54:29.023470", "lvl": "target", "msg": "[    3.559606] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2024-12-08T09:54:29.025301", "lvl": "target", "msg": "[    3.569752] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2024-12-08T09:54:29.086850", "lvl": "target", "msg": "[    3.628827] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2024-12-08T09:54:29.149631", "lvl": "target", "msg": "[    3.668016] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2024-12-08T09:54:29.150159", "lvl": "target", "msg": "[    3.672993] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2024-12-08T09:54:29.150475", "lvl": "target", "msg": "[    3.681163] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2024-12-08T09:54:29.150749", "lvl": "target", "msg": "[    3.687207] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2024-12-08T09:54:29.151006", "lvl": "target", "msg": "[    3.693248] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2024-12-08T09:54:29.151254", "lvl": "target", "msg": "[    3.699286] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2024-12-08T09:54:29.152282", "lvl": "target", "msg": "[    3.705325] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2024-12-08T09:54:29.211892", "lvl": "target", "msg": "[    3.711365] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2024-12-08T09:54:29.212400", "lvl": "target", "msg": "[    3.717401] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2024-12-08T09:54:29.212698", "lvl": "target", "msg": "[    3.723432] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2024-12-08T09:54:29.213005", "lvl": "target", "msg": "[    3.731514] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2024-12-08T09:54:29.213262", "lvl": "target", "msg": "[    3.743803] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2024-12-08T09:54:29.213503", "lvl": "target", "msg": "[    3.754965] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2024-12-08T09:54:29.215042", "lvl": "target", "msg": "[    3.766075] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2024-12-08T09:54:29.249640", "lvl": "target", "msg": "[    3.776562] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-08T09:54:29.250209", "lvl": "target", "msg": "[    3.782727] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-08T09:54:29.250529", "lvl": "target", "msg": "[    3.788127] renesas_sdhi_internal_dmac ee140000.sd: Using IPMMU context 1"}
- {"dt": "2024-12-08T09:54:29.252760", "lvl": "target", "msg": "[    3.794910] renesas_sdhi_internal_dmac ee140000.sd: Adding to iommu group 0"}
- {"dt": "2024-12-08T09:54:29.335221", "lvl": "target", "msg": "[    3.850620] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz"}
- {"dt": "2024-12-08T09:54:29.335760", "lvl": "target", "msg": "[    3.860392] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-12-08T09:54:29.336058", "lvl": "target", "msg": "[    3.867422] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-08T09:54:29.336329", "lvl": "target", "msg": "[    3.875081] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-08T09:54:29.336584", "lvl": "target", "msg": "[    3.882332] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-08T09:54:29.336865", "lvl": "target", "msg": "[    3.887878] usbhid: USB HID core driver"}
- {"dt": "2024-12-08T09:54:29.353696", "lvl": "target", "msg": "[    3.893413] irq: type mismatch, failed to map hwirq-39 for interrupt-controller@f1010000!"}
- {"dt": "2024-12-08T09:54:29.426343", "lvl": "target", "msg": "[    3.947599] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2024-12-08T09:54:29.426865", "lvl": "target", "msg": "[    3.953399] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2024-12-08T09:54:29.427169", "lvl": "target", "msg": "[    3.958297] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2024-12-08T09:54:29.427442", "lvl": "target", "msg": "[    3.964590] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2024-12-08T09:54:29.429555", "lvl": "target", "msg": "[    3.970696] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (234:0)"}
- {"dt": "2024-12-08T09:54:29.429979", "lvl": "target", "msg": "[    3.979543]  mmcblk0: p1 p2"}
- {"dt": "2024-12-08T09:54:29.515102", "lvl": "target", "msg": "[    4.042546] optee: probing for conduit method from DT."}
- {"dt": "2024-12-08T09:54:29.515634", "lvl": "target", "msg": "[    4.047682] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2024-12-08T09:54:29.515943", "lvl": "target", "msg": "[    4.048313] optee driver R-Car Rev.1.0.11"}
- {"dt": "2024-12-08T09:54:29.516212", "lvl": "target", "msg": "[    4.056651] optee: initialized driver"}
- {"dt": "2024-12-08T09:54:29.518351", "lvl": "target", "msg": "[    4.060634] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-08T09:54:29.558136", "lvl": "target", "msg": "[    4.078814] rcar_sound ec500000.sound: probed"}
- {"dt": "2024-12-08T09:54:29.558615", "lvl": "target", "msg": "[    4.083397] drop_monitor: Initializing network drop monitor service"}
- {"dt": "2024-12-08T09:54:29.558924", "lvl": "target", "msg": "[    4.089776] NET: Registered protocol family 17"}
- {"dt": "2024-12-08T09:54:29.559199", "lvl": "target", "msg": "[    4.094326] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-12-08T09:54:29.559453", "lvl": "target", "msg": "[    4.098614] Key type dns_resolver registered"}
- {"dt": "2024-12-08T09:54:29.559699", "lvl": "target", "msg": "[    4.103210] registered taskstats version 1"}
- {"dt": "2024-12-08T09:54:29.560693", "lvl": "target", "msg": "[    4.107291] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-08T09:54:29.604685", "lvl": "target", "msg": "[    4.122632] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2024-12-08T09:54:29.605180", "lvl": "target", "msg": "[    4.128533] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2024-12-08T09:54:29.605483", "lvl": "target", "msg": "[    4.133733] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2024-12-08T09:54:29.605753", "lvl": "target", "msg": "[    4.139355] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2024-12-08T09:54:29.606008", "lvl": "target", "msg": "[    4.147750] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-12-08T09:54:29.606983", "lvl": "target", "msg": "[    4.154342] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-12-08T09:54:29.607983", "lvl": "target", "msg": "[    4.161320] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2024-12-08T09:54:29.623220", "lvl": "target", "msg": "[    4.169034] [drm] Device feb00000.display probed"}
- {"dt": "2024-12-08T09:54:30.015763", "lvl": "target", "msg": "[    4.530005] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-08T09:54:30.016309", "lvl": "target", "msg": "[    4.552442] rcar-du feb00000.display: fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2024-12-08T09:54:30.018148", "lvl": "target", "msg": "[    4.563138] bd9571mwv 7-0030: Device: BD9571MWV rev. 4"}
- {"dt": "2024-12-08T09:54:30.087767", "lvl": "target", "msg": "[    4.629342] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz"}
- {"dt": "2024-12-08T09:54:30.166380", "lvl": "target", "msg": "[    4.687038] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 200 MHz"}
- {"dt": "2024-12-08T09:54:30.167621", "lvl": "target", "msg": "[    4.697271] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: sink widget TX overwritten"}
- {"dt": "2024-12-08T09:54:30.168013", "lvl": "target", "msg": "[    4.705229] asoc-audio-graph-card sound: ak4613-hifi <-> rsnd-dai.0 mapping ok"}
- {"dt": "2024-12-08T09:54:30.168357", "lvl": "target", "msg": "[    4.712641] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.1 mapping ok"}
- {"dt": "2024-12-08T09:54:30.168642", "lvl": "target", "msg": "[    4.719683] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.2 mapping ok"}
- {"dt": "2024-12-08T09:54:30.191500", "lvl": "target", "msg": "[    4.726702] asoc-audio-graph-card sound: ASoC: no DMI vendor name!"}
- {"dt": "2024-12-08T09:54:30.193791", "lvl": "target", "msg": "[    4.737535] hctosys: unable to open rtc device (rtc0)"}
- {"dt": "2024-12-08T09:54:30.284715", "lvl": "target", "msg": "[    4.816058] 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-08T09:54:38.245677", "lvl": "target", "msg": "[   12.783312] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-08T09:54:40.783501", "lvl": "target", "msg": "[   12.804821] Sending DHCP requests .., OK"}
- {"dt": "2024-12-08T09:54:40.784055", "lvl": "target", "msg": "[   15.304742] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2024-12-08T09:54:40.784371", "lvl": "target", "msg": "[   15.312539] IP-Config: Complete:"}
- {"dt": "2024-12-08T09:54:40.784647", "lvl": "target", "msg": "[   15.315752]      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-08T09:54:40.784960", "lvl": "target", "msg": "[   15.325961]      host=192.168.66.11, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-08T09:54:40.785217", "lvl": "target", "msg": "[   15.332800]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2024-12-08T09:54:40.786655", "lvl": "target", "msg": "[   15.332802]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-08T09:54:40.808001", "lvl": "target", "msg": "[   15.342947] ALSA device list:"}
- {"dt": "2024-12-08T09:54:40.808519", "lvl": "target", "msg": "[   15.345905]   #0: rcar-sound"}
- {"dt": "2024-12-08T09:54:40.811179", "lvl": "target", "msg": "[   15.352178] Freeing unused kernel memory: 6080K"}
- {"dt": "2024-12-08T09:54:40.827162", "lvl": "target", "msg": "[   15.368924] Run /init as init process"}
- {"dt": "2024-12-08T09:54:40.843111", "lvl": "target", "msg": "Starting logging: OK"}
- {"dt": "2024-12-08T09:54:40.887672", "lvl": "target", "msg": "Populating /dev using udev: [   15.404472] udevd[317]: starting version 3.2.5"}
- {"dt": "2024-12-08T09:54:40.888211", "lvl": "target", "msg": "[   15.409322] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-08T09:54:40.888510", "lvl": "target", "msg": "[   15.415855] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-08T09:54:40.888809", "lvl": "target", "msg": "[   15.422292] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-12-08T09:54:40.890885", "lvl": "target", "msg": "[   15.432523] udevd[318]: starting eudev-3.2.5"}
- {"dt": "2024-12-08T09:54:41.114475", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-08T09:54:41.115014", "lvl": "target", "msg": "Initializing random number generator... done."}
- {"dt": "2024-12-08T09:54:41.130375", "lvl": "target", "msg": "Starting network: ip: RTNETLINK answers: File exists"}
- {"dt": "2024-12-08T09:54:41.130913", "lvl": "target", "msg": "FAIL"}
- {"dt": "2024-12-08T09:54:41.162347", "lvl": "target", "msg": "Starting dropbear sshd: OK"}
- {"dt": "2024-12-08T09:54:41.162887", "lvl": "target", "msg": "/bin/sh: can't access tty; job control turned off"}
- {"dt": "2024-12-08T09:54:41.163904", "lvl": "debug", "msg": "Matched prompt #12: / #"}
- {"dt": "2024-12-08T09:54:41.164267", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "14.65", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-08T09:54:41.164853", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2024-12-08T09:54:41.165264", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:15) [common]"}
- {"dt": "2024-12-08T09:54:41.165559", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "14.65", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-08T09:54:41.166130", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:16) [common]"}
- {"dt": "2024-12-08T09:54:41.166490", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:41) [common]"}
- {"dt": "2024-12-08T09:54:41.166859", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:41) [common]"}
- {"dt": "2024-12-08T09:54:41.167244", "lvl": "info", "msg": "start: 3 finalize (timeout 00:30:00) [common]"}
- {"dt": "2024-12-08T09:54:41.167442", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2024-12-08T09:54:41.167573", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2024-12-08T09:54:41.277435", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-08T09:54:41.296324", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-12-08T09:54:41.397287", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-12-08T09:54:41.397888", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "0.23", "extra": {"status": "success"}, "level": "3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-08T09:54:41.398603", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2024-12-08T09:54:42.400523", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-08T09:54:42.401231", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-08T09:54:42.401609", "lvl": "target", "msg": "/ # "}
- {"dt": "2024-12-08T09:54:42.502602", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:42.503242", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-12-08T09:54:42.503740", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-08T09:54:42.504195", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/ramdisk"}
- {"dt": "2024-12-08T09:54:42.506672", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/kernel"}
- {"dt": "2024-12-08T09:54:42.510443", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38218/tftp-deploy-vnhp7o5a/dtb"}
- {"dt": "2024-12-08T09:54:42.510751", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38218"}
- {"dt": "2024-12-08T09:54:42.520080", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38218"}
- {"dt": "2024-12-08T09:54:42.520276", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-08T09:54:42.520354", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
