- {"dt": "2024-10-28T13:10:14.172522", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-10-28T13:10:14.174060", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-10-28T13:10:14.175159", "lvl": "info", "msg": "Start time: 2024-10-28 13:10:14.175103+00:00 (UTC)"}
- {"dt": "2024-10-28T13:10:14.176407", "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-10-28T13:10:15.911825", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true exists"}
- {"dt": "2024-10-28T13:10:18.867281", "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-10-28T13:10:19.481598", "lvl": "info", "msg": "validate duration: 5.31"}
- {"dt": "2024-10-28T13:10:19.484588", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-10-28T13:10:19.487029", "lvl": "info", "msg": "start: 1 tftp-deploy (timeout 01:30:00) [common]"}
- {"dt": "2024-10-28T13:10:19.488405", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2024-10-28T13:10:19.489509", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2024-10-28T13:10:19.490794", "lvl": "debug", "msg": "Not decompressing ramdisk as can be used compressed."}
- {"dt": "2024-10-28T13:10:19.491784", "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-10-28T13:10:19.492792", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/ramdisk/rootfs.cpio.gz"}
- {"dt": "2024-10-28T13:10:19.493792", "lvl": "debug", "msg": "total size: 4145016 (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:19.936599", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:19.970104", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:19.982169", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:19.993955", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.006646", "lvl": "debug", "msg": "progress  20\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.020196", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.031440", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.045810", "lvl": "debug", "msg": "progress  35\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.056778", "lvl": "debug", "msg": "progress  40\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.066952", "lvl": "debug", "msg": "progress  45\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.078498", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.088408", "lvl": "debug", "msg": "progress  55\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.098682", "lvl": "debug", "msg": "progress  60\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.110490", "lvl": "debug", "msg": "progress  65\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.120544", "lvl": "debug", "msg": "progress  70\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.130055", "lvl": "debug", "msg": "progress  75\u202F% (2\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.140377", "lvl": "debug", "msg": "progress  80\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.149598", "lvl": "debug", "msg": "progress  85\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.159146", "lvl": "debug", "msg": "progress  90\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.170842", "lvl": "debug", "msg": "progress  95\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.180043", "lvl": "debug", "msg": "progress 100\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.181551", "lvl": "info", "msg": "3\u202FMB downloaded in 0.69\u202Fs (5.75\u202FMB/s)"}
- {"dt": "2024-10-28T13:10:20.182586", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-10-28T13:10:20.183127", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.69", "extra": {"label": "ramdisk", "md5sum": "4c89fd138cbd1f1cbbeea8f7aadd7364", "sha256sum": "cb162eee76c8bbf1987749dd6eb7f667c6dd8175a8550df2dd63e2fb1fd8cb05", "sha512sum": "5cdbe0e30431c92b62b86f06891a19b4c6074fdebba51f75fb9139839f14a9ab6a277a459e41619b469c4bfd9d5112bce53ebcf02dbbc2e96150acc68b23163b", "size": ! "4145016"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:10:20.184329", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-10-28T13:10:20.184914", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2024-10-28T13:10:20.185478", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2024-10-28T13:10:20.186375", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/aglrefhw/Image?raw=true"}
- {"dt": "2024-10-28T13:10:20.186900", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/kernel/Image"}
- {"dt": "2024-10-28T13:10:20.187287", "lvl": "debug", "msg": "total size: 31236608 (29\u202FMB)"}
- {"dt": "2024-10-28T13:10:20.187686", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-10-28T13:10:21.800636", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:21.875462", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-10-28T13:10:21.949966", "lvl": "debug", "msg": "progress  10\u202F% (3\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.024822", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.118495", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.198250", "lvl": "debug", "msg": "progress  25\u202F% (7\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.270659", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.348356", "lvl": "debug", "msg": "progress  35\u202F% (10\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.429156", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.523032", "lvl": "debug", "msg": "progress  45\u202F% (13\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.608640", "lvl": "debug", "msg": "progress  50\u202F% (14\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.696564", "lvl": "debug", "msg": "progress  55\u202F% (16\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.791779", "lvl": "debug", "msg": "progress  60\u202F% (17\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.873223", "lvl": "debug", "msg": "progress  65\u202F% (19\u202FMB)"}
- {"dt": "2024-10-28T13:10:22.965602", "lvl": "debug", "msg": "progress  70\u202F% (20\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.056345", "lvl": "debug", "msg": "progress  75\u202F% (22\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.137300", "lvl": "debug", "msg": "progress  80\u202F% (23\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.234872", "lvl": "debug", "msg": "progress  85\u202F% (25\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.313283", "lvl": "debug", "msg": "progress  90\u202F% (26\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.393474", "lvl": "debug", "msg": "progress  95\u202F% (28\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.463770", "lvl": "debug", "msg": "progress 100\u202F% (29\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.465010", "lvl": "info", "msg": "29\u202FMB downloaded in 3.28\u202Fs (9.09\u202FMB/s)"}
- {"dt": "2024-10-28T13:10:23.466056", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-10-28T13:10:23.466621", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.28", "extra": {"label": "kernel", "md5sum": "c62d5c1f0e161443b1084db5ccd4fba0", "sha256sum": "fe49dd432ec26cad74258f7f9a639a601fef68698dbe0ea0c647c2e163fc74a0", "sha512sum": "1f5c7b57c8560658157b50d6096abaa8c568b9bdb4ce71031cb889c0ece424e2c86e1e2fdfafa15f3ec48fa963f697d126196a531c59c15dbdf52df896aa7a02", "size": ! "31236608", "success": {"sha256": "fe49dd432ec26cad74258f7f9a639a601fef68698dbe0ea0c647c2e163fc74a0"}}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:10:23.467987", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-10-28T13:10:23.468783", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:23.469577", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:23.470560", "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-10-28T13:10:23.471156", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-10-28T13:10:23.471603", "lvl": "debug", "msg": "total size: 81477 (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.472047", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-10-28T13:10:23.677150", "lvl": "debug", "msg": "progress  40\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.680604", "lvl": "debug", "msg": "progress  80\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.683283", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-10-28T13:10:23.684774", "lvl": "info", "msg": "0\u202FMB downloaded in 0.21\u202Fs (0.36\u202FMB/s)"}
- {"dt": "2024-10-28T13:10:23.686039", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:23.686750", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.22", "extra": {"label": "dtb", "md5sum": "23bfbfc1a714fa2b88a38e4dfeb5f76d", "sha256sum": "e25571abe798349b22ddb0d1e560a499b94814724288b6a6e713b6e1f0759871", "sha512sum": "1ffd5bf71122d3c0b5978d28f74797bf5211ac8d43ab35ef565be0a012d9608098997d91baf01d304ace5a80d15753af1c28589a8ce0fbae15c03b4db2cc4788", "size": ! "81477", "success": {"sha256": "e25571abe798349b22ddb0d1e560a499b94814724288b6a6e713b6e1f0759871"}}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:10:23.688351", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:23.689226", "lvl": "debug", "msg": "start: 1.4 prepare-tftp-overlay (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:23.689986", "lvl": "debug", "msg": "start: 1.4.1 extract-nfsrootfs (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:23.690648", "lvl": "debug", "msg": "end: 1.4.1 extract-nfsrootfs (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:23.691396", "lvl": "debug", "msg": "start: 1.4.2 lava-overlay (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:23.692089", "lvl": "info", "msg": "[common] skipped lava-overlay - no test action."}
- {"dt": "2024-10-28T13:10:23.692949", "lvl": "debug", "msg": "end: 1.4.2 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:23.693784", "lvl": "debug", "msg": "start: 1.4.3 extract-overlay-ramdisk (timeout 01:29:56) [common]"}
- {"dt": "2024-10-28T13:10:24.647398", "lvl": "debug", "msg": "end: 1.4.3 extract-overlay-ramdisk (duration 00:00:01) [common]"}
- {"dt": "2024-10-28T13:10:24.648428", "lvl": "debug", "msg": "start: 1.4.4 extract-modules (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.649208", "lvl": "debug", "msg": "end: 1.4.4 extract-modules (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:24.649951", "lvl": "debug", "msg": "start: 1.4.5 apply-overlay-tftp (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.650708", "lvl": "debug", "msg": "end: 1.4.5 apply-overlay-tftp (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:24.651310", "lvl": "debug", "msg": "start: 1.4.6 prepare-kernel (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.651884", "lvl": "debug", "msg": "start: 1.4.6.1 uboot-prepare-kernel (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.652461", "lvl": "debug", "msg": "end: 1.4.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:24.653059", "lvl": "debug", "msg": "end: 1.4.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:24.653666", "lvl": "debug", "msg": "start: 1.4.7 configure-preseed-file (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.654370", "lvl": "debug", "msg": "end: 1.4.7 configure-preseed-file (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:24.655082", "lvl": "debug", "msg": "start: 1.4.8 compress-ramdisk (timeout 01:29:55) [common]"}
- {"dt": "2024-10-28T13:10:24.655674", "lvl": "info", "msg": "Building ramdisk /var/lib/lava/dispatcher/tmp/37339/extract-overlay-ramdisk-wzn8l_et/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/37339/extract-overlay-ramdisk-wzn8l_et/ramdisk"}
- {"dt": "2024-10-28T13:10:25.368089", "lvl": "debug", "msg": ">> 26070 blocks\n"}
- {"dt": "2024-10-28T13:10:27.021197", "lvl": "debug", "msg": "Adding RAMdisk u-boot header."}
- {"dt": "2024-10-28T13:10:27.022208", "lvl": "debug", "msg": "mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/37339/extract-overlay-ramdisk-wzn8l_et/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/37339/extract-overlay-ramdisk-wzn8l_et/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:27.100402", "lvl": "debug", "msg": "output: Image Name:   "}
- {"dt": "2024-10-28T13:10:27.101428", "lvl": "debug", "msg": "output: Created:      Mon Oct 28 13:10:27 2024"}
- {"dt": "2024-10-28T13:10:27.102095", "lvl": "debug", "msg": "output: Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-10-28T13:10:27.102761", "lvl": "debug", "msg": "output: Data Size:    4200318 Bytes = 4101.87 KiB = 4.01 MiB"}
- {"dt": "2024-10-28T13:10:27.103422", "lvl": "debug", "msg": "output: Load Address: 00000000"}
- {"dt": "2024-10-28T13:10:27.103860", "lvl": "debug", "msg": "output: Entry Point:  00000000"}
- {"dt": "2024-10-28T13:10:27.104449", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-10-28T13:10:27.105214", "lvl": "debug", "msg": "rename /var/lib/lava/dispatcher/tmp/37339/extract-overlay-ramdisk-wzn8l_et/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:27.106046", "lvl": "debug", "msg": "end: 1.4.8 compress-ramdisk (duration 00:00:02) [common]"}
- {"dt": "2024-10-28T13:10:27.106723", "lvl": "debug", "msg": "end: 1.4 prepare-tftp-overlay (duration 00:00:03) [common]"}
- {"dt": "2024-10-28T13:10:27.107358", "lvl": "debug", "msg": "start: 1.5 lxc-create-udev-rule-action (timeout 01:29:52) [common]"}
- {"dt": "2024-10-28T13:10:27.107897", "lvl": "debug", "msg": "No LXC device requested"}
- {"dt": "2024-10-28T13:10:27.108718", "lvl": "debug", "msg": "end: 1.5 lxc-create-udev-rule-action (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:27.109490", "lvl": "debug", "msg": "Checking files for TFTP limit of 4294967296 bytes."}
- {"dt": "2024-10-28T13:10:27.113876", "lvl": "info", "msg": "end: 1 tftp-deploy (duration 00:00:08) [common]"}
- {"dt": "2024-10-28T13:10:27.114887", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2024-10-28T13:10:27.115785", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2024-10-28T13:10:27.116506", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:27.117263", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2024-10-28T13:10:27.118102", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-10-28T13:10:27.118625", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-10-28T13:10:27.119071", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2024-10-28T13:10:27.119490", "lvl": "debug", "msg": "- {DTB}: 37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-10-28T13:10:27.119912", "lvl": "debug", "msg": "- {INITRD}: 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:27.120397", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2024-10-28T13:10:27.121005", "lvl": "debug", "msg": "- {KERNEL}: 37339/tftp-deploy-qdg826re/kernel/Image"}
- {"dt": "2024-10-28T13:10:27.121605", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-10-28T13:10:27.122053", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-10-28T13:10:27.122431", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-10-28T13:10:27.122811", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2024-10-28T13:10:27.123184", "lvl": "debug", "msg": "- {RAMDISK}: 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:27.123557", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-10-28T13:10:27.123928", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-10-28T13:10:27.124302", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-10-28T13:10:27.124673", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-10-28T13:10:27.125118", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-10-28T13:10:27.125633", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-10-28T13:10:27.126273", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-10-28T13:10:27.126841", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:27.127376", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:27.127869", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-10-28T13:10:27.128249", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-10-28T13:10:27.128621", "lvl": "info", "msg": "- tftp 0x48080000 37339/tftp-deploy-qdg826re/kernel/Image"}
- {"dt": "2024-10-28T13:10:27.129054", "lvl": "info", "msg": "- tftp 0x4c000000 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:27.129445", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-10-28T13:10:27.129814", "lvl": "info", "msg": "- tftp 0x48000000 37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-10-28T13:10:27.130186", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-10-28T13:10:27.130559", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-10-28T13:10:27.131085", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-10-28T13:10:27.131519", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.01", "extra": {"dtb_addr": "0x48000000", "kernel_addr": "0x48080000", "ramdisk_addr": "0x4c000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:10:27.132672", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2024-10-28T13:10:27.133161", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2024-10-28T13:10:30.253990", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-10-28T13:10:30.261745", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2024-10-28T13:10:30.263063", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2024-10-28T13:10:30.264194", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2024-10-28T13:10:30.265340", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2024-10-28T13:10:30.267010", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2024-10-28T13:10:33.693679", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-10-28T13:10:34.759428", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-10-28T13:10:34.802517", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2024-10-28T13:10:34.905106", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2024-10-28T13:10:34.906974", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.64", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:10:34.910505", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2024-10-28T13:10:34.912454", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2024-10-28T13:10:34.914315", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-10-28T13:10:34.916246", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2024-10-28T13:10:34.918263", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-10-28T13:10:34.919206", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-10-28T13:10:34.920056", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-10-28T13:10:35.023850", "lvl": "target", "msg": "\0\0[    0.000149] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2024-10-28T13:10:35.025739", "lvl": "target", "msg": "[    0.004585] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2024-10-28T13:10:35.027258", "lvl": "target", "msg": "[    0.010118] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-10-28T13:10:35.028747", "lvl": "target", "msg": "[    0.014780] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2024-10-28T13:10:35.030311", "lvl": "target", "msg": "[    0.019553] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2024-10-28T13:10:35.032021", "lvl": "target", "msg": "[    0.024721] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-10-28T13:10:35.033178", "lvl": "target", "msg": "[    0.028762] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-10-28T13:10:35.033639", "lvl": "target", "msg": "[    0.034738] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.081250", "lvl": "target", "msg": "[    0.040600] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.082644", "lvl": "target", "msg": "[    0.046475] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.083475", "lvl": "target", "msg": "[    0.052350] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.084227", "lvl": "target", "msg": "[    0.058267] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2024-10-28T13:10:35.084993", "lvl": "target", "msg": "[    0.073569] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-10-28T13:10:35.085733", "lvl": "target", "msg": "[    0.083132] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-10-28T13:10:35.086459", "lvl": "target", "msg": "[    0.086432] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-10-28T13:10:35.087324", "lvl": "target", "msg": "[    0.091875] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-10-28T13:10:35.099958", "lvl": "target", "msg": "[    0.097232] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-10-28T13:10:35.140398", "lvl": "target", "msg": "[    0.114717] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2024-10-28T13:10:35.143396", "lvl": "target", "msg": "[    0.118361] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2024-10-28T13:10:35.145511", "lvl": "target", "msg": "[    0.123548] NOTICE:  BL2: Normal boot"}
- {"dt": "2024-10-28T13:10:35.146816", "lvl": "target", "msg": "[    0.127183] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2024-10-28T13:10:35.148083", "lvl": "target", "msg": "[    0.133726] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2024-10-28T13:10:35.149095", "lvl": "target", "msg": "[    0.139842] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2024-10-28T13:10:35.150082", "lvl": "target", "msg": "[    0.147443] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2024-10-28T13:10:35.157913", "lvl": "target", "msg": "[    0.156420] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2024-10-28T13:10:35.189912", "lvl": "target", "msg": "[    0.187383] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2024-10-28T13:10:35.206050", "lvl": "target", "msg": "[    0.216123] NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-10-28T13:10:35.443813", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:10:35.444456", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:10:35.444983", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2024-10-28T13:10:35.445520", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:10:35.446006", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-10-28T13:10:35.447311", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-10-28T13:10:35.814080", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-10-28T13:10:35.814939", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-10-28T13:10:35.815765", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.817965", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.818798", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-10-28T13:10:35.819596", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:10:35.849109", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2024-10-28T13:10:36.179306", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-10-28T13:10:36.180928", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-10-28T13:10:36.182186", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-10-28T13:10:36.184043", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-10-28T13:10:36.230240", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-10-28T13:10:36.232538", "lvl": "input", "msg": "\n"}
- {"dt": "2024-10-28T13:10:36.284401", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2024-10-28T13:10:36.286769", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2024-10-28T13:10:36.288735", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2024-10-28T13:10:36.290485", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2024-10-28T13:10:36.292105", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2024-10-28T13:10:36.309201", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2024-10-28T13:10:36.311067", "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-10-28T13:10:36.311906", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-10-28T13:10:36.413034", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2024-10-28T13:10:36.414092", "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:51)"}
- {"dt": "2024-10-28T13:10:36.421584", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-10-28T13:10:36.422781", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2024-10-28T13:10:36.524141", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:36.526404", "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:51)"}
- {"dt": "2024-10-28T13:10:36.533281", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:36.534233", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2024-10-28T13:10:36.635403", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:36.637860", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2024-10-28T13:10:36.645183", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2024-10-28T13:10:36.646988", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-10-28T13:10:36.748294", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2024-10-28T13:10:36.749878", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2024-10-28T13:10:36.755907", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-10-28T13:10:42.836944", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2024-10-28T13:10:42.838612", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-10-28T13:10:43.092089", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-10-28T13:10:43.602840", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-10-28T13:10:44.130107", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.28 (1278 ms)"}
- {"dt": "2024-10-28T13:10:44.131074", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-10-28T13:10:44.232522", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2024-10-28T13:10:44.233829", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43)"}
- {"dt": "2024-10-28T13:10:44.240837", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-10-28T13:10:44.241775", "lvl": "input", "msg": "tftp 0x48080000 37339/tftp-deploy-qdg826re/kernel/Image\n"}
- {"dt": "2024-10-28T13:10:44.342728", "lvl": "target", "msg": "=> tftp 0x48080000 37339/tftp-deploy-qdg826re/kernel/Image"}
- {"dt": "2024-10-28T13:10:44.344547", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43)"}
- {"dt": "2024-10-28T13:10:44.353620", "lvl": "target", "msg": "tftp 0x48080000 37339/tftp-deploy-qdg826re/kernel/Image"}
- {"dt": "2024-10-28T13:10:44.397397", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-10-28T13:10:44.398531", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.28"}
- {"dt": "2024-10-28T13:10:44.399517", "lvl": "target", "msg": "Filename '37339/tftp-deploy-qdg826re/kernel/Image'."}
- {"dt": "2024-10-28T13:10:44.400198", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2024-10-28T13:10:49.702129", "lvl": "target", "msg": "Loading: *\bT #################################################################"}
- {"dt": "2024-10-28T13:10:50.021935", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:50.340925", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:50.643867", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:50.963084", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:51.266294", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:51.585529", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:51.904688", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:52.223725", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:52.543119", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:52.862211", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:53.181459", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:53.500560", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:53.819850", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:54.123124", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:54.426559", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:54.761686", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:55.080979", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:55.384172", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:55.703192", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:56.022478", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:56.357730", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:56.676976", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:56.996064", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:57.315129", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:57.634274", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:57.953624", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:58.256781", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:58.576071", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:58.879512", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:59.214538", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:59.517806", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:10:59.757133", "lvl": "target", "msg": "\t ################################################"}
- {"dt": "2024-10-28T13:10:59.773450", "lvl": "target", "msg": "\t 1.9 MiB/s"}
- {"dt": "2024-10-28T13:10:59.775145", "lvl": "target", "msg": "done"}
- {"dt": "2024-10-28T13:10:59.776676", "lvl": "target", "msg": "Bytes transferred = 31236608 (1dca200 hex)"}
- {"dt": "2024-10-28T13:10:59.779048", "lvl": "input", "msg": "tftp 0x4c000000 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot\n"}
- {"dt": "2024-10-28T13:10:59.880252", "lvl": "target", "msg": "=> tftp 0x4c000000 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:59.883563", "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:27)"}
- {"dt": "2024-10-28T13:10:59.924796", "lvl": "target", "msg": "tftp 0x4c000000 37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-10-28T13:10:59.926614", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-10-28T13:10:59.965765", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.28"}
- {"dt": "2024-10-28T13:10:59.967468", "lvl": "target", "msg": "Filename '37339/tftp-deploy-qdg826re/ramdisk/ramdisk.cpio.gz.uboot'."}
- {"dt": "2024-10-28T13:10:59.969082", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2024-10-28T13:11:00.246011", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-10-28T13:11:00.565319", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:11:00.900251", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:11:01.219593", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-10-28T13:11:01.347122", "lvl": "target", "msg": "\t ###########################"}
- {"dt": "2024-10-28T13:11:01.348639", "lvl": "target", "msg": "\t 2.8 MiB/s"}
- {"dt": "2024-10-28T13:11:01.350258", "lvl": "target", "msg": "done"}
- {"dt": "2024-10-28T13:11:01.363067", "lvl": "target", "msg": "Bytes transferred = 4200382 (4017be hex)"}
- {"dt": "2024-10-28T13:11:01.365229", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-10-28T13:11:01.466664", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2024-10-28T13:11:01.468655", "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:26)"}
- {"dt": "2024-10-28T13:11:01.474944", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-10-28T13:11:01.477042", "lvl": "input", "msg": "tftp 0x48000000 37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb\n"}
- {"dt": "2024-10-28T13:11:01.577886", "lvl": "target", "msg": "=> tftp 0x48000000 37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-10-28T13:11:01.579142", "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:26)"}
- {"dt": "2024-10-28T13:11:01.584724", "lvl": "target", "msg": "tftp 0x48000000 37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb"}
- {"dt": "2024-10-28T13:11:01.630959", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-10-28T13:11:01.633031", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.28"}
- {"dt": "2024-10-28T13:11:01.634886", "lvl": "target", "msg": "Filename '37339/tftp-deploy-qdg826re/dtb/r8a7795-agl-refhw--5.4.0+git0+289de10299-r1-h3ulcb-20201210131503.dtb'."}
- {"dt": "2024-10-28T13:11:01.636249", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2024-10-28T13:11:01.649587", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2024-10-28T13:11:01.650538", "lvl": "target", "msg": "\t 2.5 MiB/s"}
- {"dt": "2024-10-28T13:11:01.651382", "lvl": "target", "msg": "done"}
- {"dt": "2024-10-28T13:11:01.665511", "lvl": "target", "msg": "Bytes transferred = 81477 (13e45 hex)"}
- {"dt": "2024-10-28T13:11:01.666858", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'\n"}
- {"dt": "2024-10-28T13:11:01.768604", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-10-28T13:11:01.770642", "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:25)"}
- {"dt": "2024-10-28T13:11:01.775022", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp'"}
- {"dt": "2024-10-28T13:11:01.777172", "lvl": "input", "msg": "booti 0x48080000 0x4c000000 0x48000000\n"}
- {"dt": "2024-10-28T13:11:01.879328", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-10-28T13:11:01.881400", "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-10-28T13:11:01.883165", "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:25)"}
- {"dt": "2024-10-28T13:11:01.887124", "lvl": "target", "msg": "booti 0x48080000 0x4c000000 0x48000000"}
- {"dt": "2024-10-28T13:11:01.916678", "lvl": "target", "msg": "## Loading init Ramdisk from Legacy Image at 4c000000 ..."}
- {"dt": "2024-10-28T13:11:01.917982", "lvl": "target", "msg": "   Image Name:   "}
- {"dt": "2024-10-28T13:11:01.919704", "lvl": "target", "msg": "   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-10-28T13:11:01.920963", "lvl": "target", "msg": "   Data Size:    4200318 Bytes = 4 MiB"}
- {"dt": "2024-10-28T13:11:01.922174", "lvl": "target", "msg": "   Load Address: 00000000"}
- {"dt": "2024-10-28T13:11:01.923542", "lvl": "target", "msg": "   Entry Point:  00000000"}
- {"dt": "2024-10-28T13:11:01.953571", "lvl": "target", "msg": "   Verifying Checksum ... OK"}
- {"dt": "2024-10-28T13:11:01.954993", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2024-10-28T13:11:01.956745", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2024-10-28T13:11:01.958307", "lvl": "target", "msg": "   Loading Ramdisk to bfa03000, end bfe0477e ... OK"}
- {"dt": "2024-10-28T13:11:01.976465", "lvl": "target", "msg": "   Loading Device Tree to 00000000bf9ec000, end 00000000bfa02e44 ... OK"}
- {"dt": "2024-10-28T13:11:01.977442", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:11:01.979817", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-10-28T13:11:01.980696", "lvl": "target", "msg": ""}
- {"dt": "2024-10-28T13:11:01.981696", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:26) [common]"}
- {"dt": "2024-10-28T13:11:01.982406", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:25) [common]"}
- {"dt": "2024-10-28T13:11:01.982977", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-10-28T13:11:01.983638", "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-10-28T13:11:01.984173", "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-10-28T13:11:03.615130", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2024-10-28T13:11:03.617810", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:23) [common]"}
- {"dt": "2024-10-28T13:11:03.619481", "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-10-28T13:11:03.621283", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-10-28T13:11:03.623029", "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-10-28T13:11:03.624749", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-10-28T13:11:03.625682", "lvl": "debug", "msg": "No login prompt set."}
- {"dt": "2024-10-28T13:11:03.626141", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-10-28T13:11:03.626561", "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-10-28T13:11:03.627475", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:23)"}
- {"dt": "2024-10-28T13:11:03.630912", "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-10-28T13:11:03.631498", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-10-28T13:11:03.631944", "lvl": "target", "msg": "[    0.000000] efi: Getting EFI parameters from FDT:"}
- {"dt": "2024-10-28T13:11:03.632365", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-10-28T13:11:03.632770", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2024-10-28T13:11:03.658242", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2024-10-28T13:11:03.660564", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2024-10-28T13:11:03.662256", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2024-10-28T13:11:03.663801", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2024-10-28T13:11:03.665584", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2024-10-28T13:11:03.701416", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-10-28T13:11:03.702712", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-10-28T13:11:03.703659", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efe3800-0x77efe4fff]"}
- {"dt": "2024-10-28T13:11:03.704720", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-10-28T13:11:03.705383", "lvl": "target", "msg": "[    0.000000]   DMA32    [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2024-10-28T13:11:03.705969", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2024-10-28T13:11:03.706519", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-10-28T13:11:03.707086", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-10-28T13:11:03.744243", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2024-10-28T13:11:03.745562", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2024-10-28T13:11:03.746131", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2024-10-28T13:11:03.746562", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2024-10-28T13:11:03.747006", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2024-10-28T13:11:03.747507", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-10-28T13:11:03.747951", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-10-28T13:11:03.787303", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-10-28T13:11:03.788318", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-10-28T13:11:03.789198", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2024-10-28T13:11:03.790669", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2024-10-28T13:11:03.791557", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 31 pages/cpu s86744 r8192 d32040 u126976"}
- {"dt": "2024-10-28T13:11:03.792395", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-10-28T13:11:03.793302", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-10-28T13:11:03.794115", "lvl": "target", "msg": "[    0.000000] Speculative Store Bypass Disable mitigation not required"}
- {"dt": "2024-10-28T13:11:03.830087", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2020032"}
- {"dt": "2024-10-28T13:11:03.831524", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-10-28T13:11:03.832569", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 root=/dev/ram0  ip=dhcp"}
- {"dt": "2024-10-28T13:11:03.833651", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2024-10-28T13:11:03.834645", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-10-28T13:11:03.836418", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2024-10-28T13:11:03.837105", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0xbb9ec000-0xbf9ec000] (64MB)"}
- {"dt": "2024-10-28T13:11:03.874017", "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-10-28T13:11:03.875040", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2024-10-28T13:11:03.875903", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-10-28T13:11:03.876728", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2024-10-28T13:11:03.877716", "lvl": "target", "msg": "[    0.000000] \tTasks RCU enabled."}
- {"dt": "2024-10-28T13:11:03.878812", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-10-28T13:11:03.916259", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2024-10-28T13:11:03.917768", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-10-28T13:11:03.918708", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2024-10-28T13:11:03.919480", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-10-28T13:11:03.920331", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x318/0x4ac with crng_init=0"}
- {"dt": "2024-10-28T13:11:03.921289", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2024-10-28T13:11:03.958796", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2024-10-28T13:11:03.960678", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2024-10-28T13:11:03.961391", "lvl": "target", "msg": "[    0.000139] Console: colour dummy device 80x25"}
- {"dt": "2024-10-28T13:11:03.962028", "lvl": "target", "msg": "[    0.000210] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2024-10-28T13:11:03.962644", "lvl": "target", "msg": "[    0.000219] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-10-28T13:11:03.963237", "lvl": "target", "msg": "[    0.000273] LSM: Security Framework initializing"}
- {"dt": "2024-10-28T13:11:03.963839", "lvl": "target", "msg": "[    0.000315] Smack:  Initializing."}
- {"dt": "2024-10-28T13:11:03.964560", "lvl": "target", "msg": "[    0.000318] Smack:  IPv6 port labeling enabled."}
- {"dt": "2024-10-28T13:11:04.002440", "lvl": "target", "msg": "[    0.000386] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.003124", "lvl": "target", "msg": "[    0.000432] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.003603", "lvl": "target", "msg": "[    0.023961] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-10-28T13:11:04.004029", "lvl": "target", "msg": "[    0.031951] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-10-28T13:11:04.004541", "lvl": "target", "msg": "[    0.041244] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2024-10-28T13:11:04.005051", "lvl": "target", "msg": "[    0.042870] EFI services will not be available."}
- {"dt": "2024-10-28T13:11:04.005483", "lvl": "target", "msg": "[    0.047981] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-10-28T13:11:04.006529", "lvl": "target", "msg": "[    0.080194] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-10-28T13:11:04.045806", "lvl": "target", "msg": "[    0.080234] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2024-10-28T13:11:04.046889", "lvl": "target", "msg": "[    0.112201] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-10-28T13:11:04.047847", "lvl": "target", "msg": "[    0.112224] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2024-10-28T13:11:04.048786", "lvl": "target", "msg": "[    0.144237] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-10-28T13:11:04.049813", "lvl": "target", "msg": "[    0.144259] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2024-10-28T13:11:04.050759", "lvl": "target", "msg": "[    0.176287] CPU features: detected: ARM erratum 845719"}
- {"dt": "2024-10-28T13:11:04.051618", "lvl": "target", "msg": "[    0.176298] Detected VIPT I-cache on CPU4"}
- {"dt": "2024-10-28T13:11:04.052434", "lvl": "target", "msg": "[    0.176338] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2024-10-28T13:11:04.088319", "lvl": "target", "msg": "[    0.208316] Detected VIPT I-cache on CPU5"}
- {"dt": "2024-10-28T13:11:04.089703", "lvl": "target", "msg": "[    0.208340] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2024-10-28T13:11:04.091001", "lvl": "target", "msg": "[    0.240366] Detected VIPT I-cache on CPU6"}
- {"dt": "2024-10-28T13:11:04.092451", "lvl": "target", "msg": "[    0.240389] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2024-10-28T13:11:04.095459", "lvl": "target", "msg": "[    0.272392] Detected VIPT I-cache on CPU7"}
- {"dt": "2024-10-28T13:11:04.096788", "lvl": "target", "msg": "[    0.272416] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2024-10-28T13:11:04.098119", "lvl": "target", "msg": "[    0.272492] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2024-10-28T13:11:04.099455", "lvl": "target", "msg": "[    0.272514] SMP: Total of 8 processors activated."}
- {"dt": "2024-10-28T13:11:04.131290", "lvl": "target", "msg": "[    0.272519] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-10-28T13:11:04.132276", "lvl": "target", "msg": "[    0.272524] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-10-28T13:11:04.133098", "lvl": "target", "msg": "[    0.282872] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-10-28T13:11:04.133880", "lvl": "target", "msg": "[    0.282903] alternatives: patching kernel code"}
- {"dt": "2024-10-28T13:11:04.134793", "lvl": "target", "msg": "[    0.283704] devtmpfs: initialized"}
- {"dt": "2024-10-28T13:11:04.135660", "lvl": "target", "msg": "[    0.290200] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-10-28T13:11:04.136420", "lvl": "target", "msg": "[    0.290214] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.138020", "lvl": "target", "msg": "[    0.299817] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-10-28T13:11:04.174083", "lvl": "target", "msg": "[    0.301171] DMI not present or invalid."}
- {"dt": "2024-10-28T13:11:04.174911", "lvl": "target", "msg": "[    0.301450] NET: Registered protocol family 16"}
- {"dt": "2024-10-28T13:11:04.175489", "lvl": "target", "msg": "[    0.302251] DMA: preallocated 256 KiB pool for atomic allocations"}
- {"dt": "2024-10-28T13:11:04.176093", "lvl": "target", "msg": "[    0.302263] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-10-28T13:11:04.176718", "lvl": "target", "msg": "[    0.302394] audit: type=2000 audit(0.300:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-10-28T13:11:04.177579", "lvl": "target", "msg": "[    0.304525] cpuidle: using governor menu"}
- {"dt": "2024-10-28T13:11:04.178322", "lvl": "target", "msg": "[    0.304732] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-10-28T13:11:04.180061", "lvl": "target", "msg": "[    0.305844] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-10-28T13:11:04.217448", "lvl": "target", "msg": "[    0.308511] sh-pfc e6060000.pin-controller: IRQ index 0 not found"}
- {"dt": "2024-10-28T13:11:04.219006", "lvl": "target", "msg": "[    0.308743] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered"}
- {"dt": "2024-10-28T13:11:04.222997", "lvl": "target", "msg": "[    0.331055] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-10-28T13:11:04.224560", "lvl": "target", "msg": "[    0.331062] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-10-28T13:11:04.225728", "lvl": "target", "msg": "[    0.331067] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-10-28T13:11:04.226409", "lvl": "target", "msg": "[    0.331071] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-10-28T13:11:04.227047", "lvl": "target", "msg": "[    0.332975] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-10-28T13:11:04.259771", "lvl": "target", "msg": "[    0.335795] ACPI: Interpreter disabled."}
- {"dt": "2024-10-28T13:11:04.260428", "lvl": "target", "msg": "[    0.338756] rcar-avs: use avs value: 0"}
- {"dt": "2024-10-28T13:11:04.260841", "lvl": "target", "msg": "[    0.338767] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2024-10-28T13:11:04.261298", "lvl": "target", "msg": "[    0.338775] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2024-10-28T13:11:04.262503", "lvl": "target", "msg": "[    0.338781] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2024-10-28T13:11:04.263067", "lvl": "target", "msg": "[    0.338788] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2024-10-28T13:11:04.263521", "lvl": "target", "msg": "[    0.338795] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2024-10-28T13:11:04.263910", "lvl": "target", "msg": "[    0.339875] iommu: Default domain type: Translated "}
- {"dt": "2024-10-28T13:11:04.302532", "lvl": "target", "msg": "[    0.340346] ipmmu-vmsa e67b0000.mmu: IPMMU context 0 is reserved"}
- {"dt": "2024-10-28T13:11:04.303193", "lvl": "target", "msg": "[    0.341013] vgaarb: loaded"}
- {"dt": "2024-10-28T13:11:04.303606", "lvl": "target", "msg": "[    0.341227] SCSI subsystem initialized"}
- {"dt": "2024-10-28T13:11:04.303982", "lvl": "target", "msg": "[    0.341484] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-10-28T13:11:04.304356", "lvl": "target", "msg": "[    0.341509] usbcore: registered new interface driver hub"}
- {"dt": "2024-10-28T13:11:04.304724", "lvl": "target", "msg": "[    0.341563] usbcore: registered new device driver usb"}
- {"dt": "2024-10-28T13:11:04.305150", "lvl": "target", "msg": "[    0.342534] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2024-10-28T13:11:04.305606", "lvl": "target", "msg": "[    0.342827] mc: Linux media interface: v0.10"}
- {"dt": "2024-10-28T13:11:04.306872", "lvl": "target", "msg": "[    0.342842] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-10-28T13:11:04.345721", "lvl": "target", "msg": "[    0.342890] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-10-28T13:11:04.346392", "lvl": "target", "msg": "[    0.342894] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-10-28T13:11:04.346944", "lvl": "target", "msg": "[    0.342902] PTP clock support registered"}
- {"dt": "2024-10-28T13:11:04.347512", "lvl": "target", "msg": "[    0.343036] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-10-28T13:11:04.348097", "lvl": "target", "msg": "[    0.344402] FPGA manager framework"}
- {"dt": "2024-10-28T13:11:04.348845", "lvl": "target", "msg": "[    0.344450] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-10-28T13:11:04.349659", "lvl": "target", "msg": "[    0.344736] NetLabel: Initializing"}
- {"dt": "2024-10-28T13:11:04.350399", "lvl": "target", "msg": "[    0.344740] NetLabel:  domain hash size = 128"}
- {"dt": "2024-10-28T13:11:04.352454", "lvl": "target", "msg": "[    0.344743] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-10-28T13:11:04.389119", "lvl": "target", "msg": "[    0.344780] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-10-28T13:11:04.390460", "lvl": "target", "msg": "[    0.345111] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-10-28T13:11:04.391546", "lvl": "target", "msg": "[    0.663616] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-10-28T13:11:04.392291", "lvl": "target", "msg": "[    0.663665] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-10-28T13:11:04.392793", "lvl": "target", "msg": "[    0.663812] pnp: PnP ACPI: disabled"}
- {"dt": "2024-10-28T13:11:04.393740", "lvl": "target", "msg": "[    0.667461] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-10-28T13:11:04.394755", "lvl": "target", "msg": "[    0.668254] NET: Registered protocol family 2"}
- {"dt": "2024-10-28T13:11:04.397576", "lvl": "target", "msg": "[    0.668515] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.432279", "lvl": "target", "msg": "[    0.668577] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.434013", "lvl": "target", "msg": "[    0.668859] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.435987", "lvl": "target", "msg": "[    0.669470] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-10-28T13:11:04.437492", "lvl": "target", "msg": "[    0.669595] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.438847", "lvl": "target", "msg": "[    0.669701] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-10-28T13:11:04.440821", "lvl": "target", "msg": "[    0.669926] NET: Registered protocol family 1"}
- {"dt": "2024-10-28T13:11:04.475129", "lvl": "target", "msg": "[    0.670296] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-10-28T13:11:04.476781", "lvl": "target", "msg": "[    0.670301] RPC: Registered udp transport module."}
- {"dt": "2024-10-28T13:11:04.478452", "lvl": "target", "msg": "[    0.670304] RPC: Registered tcp transport module."}
- {"dt": "2024-10-28T13:11:04.482451", "lvl": "target", "msg": "[    0.670307] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-10-28T13:11:04.483967", "lvl": "target", "msg": "[    0.670317] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-10-28T13:11:04.485313", "lvl": "target", "msg": "[    0.670432] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-10-28T13:11:04.485744", "lvl": "target", "msg": "[    0.809482] Freeing initrd memory: 4100K"}
- {"dt": "2024-10-28T13:11:04.486159", "lvl": "target", "msg": "[    0.810300] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2024-10-28T13:11:04.518164", "lvl": "target", "msg": "[    0.810552] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2024-10-28T13:11:04.519283", "lvl": "target", "msg": "[    0.810991] kvm [1]: IPA Size Limit: 40bits"}
- {"dt": "2024-10-28T13:11:04.520259", "lvl": "target", "msg": "[    0.811529] kvm [1]: vgic interrupt IRQ1"}
- {"dt": "2024-10-28T13:11:04.522948", "lvl": "target", "msg": "[    0.811682] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-10-28T13:11:04.523996", "lvl": "target", "msg": "[    0.844567] Initialise system trusted keyrings"}
- {"dt": "2024-10-28T13:11:04.524998", "lvl": "target", "msg": "[    0.844671] workingset: timestamp_bits=44 max_order=21 bucket_order=0"}
- {"dt": "2024-10-28T13:11:04.525899", "lvl": "target", "msg": "[    0.848017] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-10-28T13:11:04.526796", "lvl": "target", "msg": "[    0.848429] NFS: Registering the id_resolver key type"}
- {"dt": "2024-10-28T13:11:04.560645", "lvl": "target", "msg": "[    0.848446] Key type id_resolver registered"}
- {"dt": "2024-10-28T13:11:04.561675", "lvl": "target", "msg": "[    0.848450] Key type id_legacy registered"}
- {"dt": "2024-10-28T13:11:04.562500", "lvl": "target", "msg": "[    0.848459] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-10-28T13:11:04.563186", "lvl": "target", "msg": "[    0.848601] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-10-28T13:11:04.563876", "lvl": "target", "msg": "[    0.859292] Key type asymmetric registered"}
- {"dt": "2024-10-28T13:11:04.564470", "lvl": "target", "msg": "[    0.859299] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-10-28T13:11:04.565003", "lvl": "target", "msg": "[    0.859333] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2024-10-28T13:11:04.565621", "lvl": "target", "msg": "[    0.859339] io scheduler mq-deadline registered"}
- {"dt": "2024-10-28T13:11:04.567381", "lvl": "target", "msg": "[    0.859344] io scheduler kyber registered"}
- {"dt": "2024-10-28T13:11:04.603489", "lvl": "target", "msg": "[    0.872124] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2024-10-28T13:11:04.605192", "lvl": "target", "msg": "[    0.872337] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2024-10-28T13:11:04.606581", "lvl": "target", "msg": "[    0.872526] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2024-10-28T13:11:04.607178", "lvl": "target", "msg": "[    0.872712] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2024-10-28T13:11:04.607726", "lvl": "target", "msg": "[    0.872905] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2024-10-28T13:11:04.608269", "lvl": "target", "msg": "[    0.873098] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2024-10-28T13:11:04.608813", "lvl": "target", "msg": "[    0.873313] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2024-10-28T13:11:04.610023", "lvl": "target", "msg": "[    0.873492] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2024-10-28T13:11:04.646525", "lvl": "target", "msg": "[    0.875173] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2024-10-28T13:11:04.647714", "lvl": "target", "msg": "[    0.875198] rcar-pcie fe000000.pcie:    IO 0xfe100000..0xfe1fffff -> 0x00000000"}
- {"dt": "2024-10-28T13:11:04.650545", "lvl": "target", "msg": "[    0.875214] rcar-pcie fe000000.pcie:   MEM 0xfe200000..0xfe3fffff -> 0xfe200000"}
- {"dt": "2024-10-28T13:11:04.651587", "lvl": "target", "msg": "[    0.875227] rcar-pcie fe000000.pcie:   MEM 0x30000000..0x37ffffff -> 0x30000000"}
- {"dt": "2024-10-28T13:11:04.652605", "lvl": "target", "msg": "[    0.875237] rcar-pcie fe000000.pcie:   MEM 0x38000000..0x3fffffff -> 0x38000000"}
- {"dt": "2024-10-28T13:11:04.653781", "lvl": "target", "msg": "[    0.944735] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2024-10-28T13:11:04.689575", "lvl": "target", "msg": "[    0.944869] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2024-10-28T13:11:04.690868", "lvl": "target", "msg": "[    0.944887] rcar-pcie ee800000.pcie:    IO 0xee900000..0xee9fffff -> 0x00000000"}
- {"dt": "2024-10-28T13:11:04.691929", "lvl": "target", "msg": "[    0.944903] rcar-pcie ee800000.pcie:   MEM 0xeea00000..0xeebfffff -> 0xeea00000"}
- {"dt": "2024-10-28T13:11:04.695131", "lvl": "target", "msg": "[    0.944915] rcar-pcie ee800000.pcie:   MEM 0xc0000000..0xc7ffffff -> 0xc0000000"}
- {"dt": "2024-10-28T13:11:04.696350", "lvl": "target", "msg": "[    0.944924] rcar-pcie ee800000.pcie:   MEM 0xc8000000..0xcfffffff -> 0xc8000000"}
- {"dt": "2024-10-28T13:11:04.697280", "lvl": "target", "msg": "[    1.008739] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2024-10-28T13:11:04.697824", "lvl": "target", "msg": "[    1.010798] EINJ: ACPI disabled."}
- {"dt": "2024-10-28T13:11:04.732471", "lvl": "target", "msg": "[    1.034591] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-10-28T13:11:04.733391", "lvl": "target", "msg": "[    1.036626] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-10-28T13:11:04.734031", "lvl": "target", "msg": "[    1.036974] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 37, base_baud = 0) is a hscif"}
- {"dt": "2024-10-28T13:11:04.734765", "lvl": "target", "msg": "[    1.037383] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 38, base_baud = 0) is a hscif"}
- {"dt": "2024-10-28T13:11:04.736767", "lvl": "target", "msg": "[    1.037598] sh-pfc e6060000.pin-controller: pin GP_6_26 already requested by ee0a0200.usb-phy; cannot claim for e6560000.serial"}
- {"dt": "2024-10-28T13:11:04.737652", "lvl": "target", "msg": "[    1.037604] sh-pfc e6060000.pin-controller: pin-218 (e6560000.serial) status -22"}
- {"dt": "2024-10-28T13:11:04.775074", "lvl": "target", "msg": "[    1.037610] sh-pfc e6060000.pin-controller: could not request pin 218 (GP_6_26) from group hscif2_data_c  on device sh-pfc"}
- {"dt": "2024-10-28T13:11:04.775952", "lvl": "target", "msg": "[    1.037616] sh-sci e6560000.serial: Error applying setting, reverse things back"}
- {"dt": "2024-10-28T13:11:04.776659", "lvl": "target", "msg": "[    1.037628] sh-sci: probe of e6560000.serial failed with error -22"}
- {"dt": "2024-10-28T13:11:04.777373", "lvl": "target", "msg": "[    1.037853] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 127, base_baud = 0) is a scif"}
- {"dt": "2024-10-28T13:11:04.778016", "lvl": "target", "msg": "[    1.038196] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 128, base_baud = 0) is a scif"}
- {"dt": "2024-10-28T13:11:04.823781", "lvl": "target", "msg": "[    2.237435] printk: console [ttySC0] enabled"}
- {"dt": "2024-10-28T13:11:04.825279", "lvl": "target", "msg": "[    2.242012] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 129, base_baud = 0) is a scif"}
- {"dt": "2024-10-28T13:11:04.826436", "lvl": "target", "msg": "[    2.251141] msm_serial: driver initialized"}
- {"dt": "2024-10-28T13:11:04.827556", "lvl": "target", "msg": "[    2.258188] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-10-28T13:11:04.828660", "lvl": "target", "msg": "[    2.264777] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-10-28T13:11:04.829599", "lvl": "target", "msg": "[    2.271083] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-10-28T13:11:04.831422", "lvl": "target", "msg": "[    2.280941] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-10-28T13:11:04.855097", "lvl": "target", "msg": "[    2.288991] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-10-28T13:11:04.856239", "lvl": "target", "msg": "[    2.298795] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-10-28T13:11:04.859139", "lvl": "target", "msg": "[    2.312255] brd: module loaded"}
- {"dt": "2024-10-28T13:11:04.873855", "lvl": "target", "msg": "[    2.320748] loop: module loaded"}
- {"dt": "2024-10-28T13:11:04.970385", "lvl": "target", "msg": "[    2.391972] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2024-10-28T13:11:04.971922", "lvl": "target", "msg": "[    2.396193] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-10-28T13:11:04.973343", "lvl": "target", "msg": "[    2.402097] thunder_xcv, ver 1.0"}
- {"dt": "2024-10-28T13:11:04.974624", "lvl": "target", "msg": "[    2.405333] thunder_bgx, ver 1.0"}
- {"dt": "2024-10-28T13:11:04.975896", "lvl": "target", "msg": "[    2.408560] nicpf, ver 1.0"}
- {"dt": "2024-10-28T13:11:04.977227", "lvl": "target", "msg": "[    2.411918] hclge is initializing"}
- {"dt": "2024-10-28T13:11:04.978496", "lvl": "target", "msg": "[    2.415218] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-10-28T13:11:04.979772", "lvl": "target", "msg": "[    2.422401] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-10-28T13:11:04.981810", "lvl": "target", "msg": "[    2.427719] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k"}
- {"dt": "2024-10-28T13:11:05.016624", "lvl": "target", "msg": "[    2.433523] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-10-28T13:11:05.018505", "lvl": "target", "msg": "[    2.439429] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k"}
- {"dt": "2024-10-28T13:11:05.019166", "lvl": "target", "msg": "[    2.446354] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-10-28T13:11:05.019773", "lvl": "target", "msg": "[    2.451917] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k"}
- {"dt": "2024-10-28T13:11:05.020372", "lvl": "target", "msg": "[    2.459707] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-10-28T13:11:05.021017", "lvl": "target", "msg": "[    2.465958] sky2: driver version 1.30"}
- {"dt": "2024-10-28T13:11:05.021611", "lvl": "target", "msg": "[    2.470314] libphy: ravb_mii: probed"}
- {"dt": "2024-10-28T13:11:05.056523", "lvl": "target", "msg": "[    2.476355] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 122."}
- {"dt": "2024-10-28T13:11:05.057543", "lvl": "target", "msg": "[    2.486069] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-10-28T13:11:05.058406", "lvl": "target", "msg": "[    2.492743] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-10-28T13:11:05.059261", "lvl": "target", "msg": "[    2.499246] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-10-28T13:11:05.061726", "lvl": "target", "msg": "[    2.503684] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-10-28T13:11:05.104266", "lvl": "target", "msg": "[    2.537169] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.104854", "lvl": "target", "msg": "[    2.542822] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-10-28T13:11:05.107428", "lvl": "target", "msg": "[    2.550681] ehci-platform ee080100.usb: irq 175, io mem 0xee080100"}
- {"dt": "2024-10-28T13:11:05.147311", "lvl": "target", "msg": "[    2.569116] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-10-28T13:11:05.147992", "lvl": "target", "msg": "[    2.575629] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.148600", "lvl": "target", "msg": "[    2.579380] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.149245", "lvl": "target", "msg": "[    2.583563] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.149844", "lvl": "target", "msg": "[    2.589205] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-10-28T13:11:05.151594", "lvl": "target", "msg": "[    2.597056] ehci-platform ee0a0100.usb: irq 176, io mem 0xee0a0100"}
- {"dt": "2024-10-28T13:11:05.199175", "lvl": "target", "msg": "[    2.617120] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-10-28T13:11:05.200109", "lvl": "target", "msg": "[    2.623522] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.201027", "lvl": "target", "msg": "[    2.627268] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.201893", "lvl": "target", "msg": "[    2.631408] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.202770", "lvl": "target", "msg": "[    2.637048] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-10-28T13:11:05.205258", "lvl": "target", "msg": "[    2.644881] ehci-platform ee0c0100.usb: irq 177, io mem 0xee0c0100"}
- {"dt": "2024-10-28T13:11:05.225994", "lvl": "target", "msg": "[    2.665119] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-10-28T13:11:05.226928", "lvl": "target", "msg": "[    2.671518] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.229426", "lvl": "target", "msg": "[    2.675263] hub 3-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.273880", "lvl": "target", "msg": "[    2.705152] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.274500", "lvl": "target", "msg": "[    2.710794] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-10-28T13:11:05.275027", "lvl": "target", "msg": "[    2.718629] ehci-platform ee0e0100.usb: irq 178, io mem 0xee0e0100"}
- {"dt": "2024-10-28T13:11:05.318202", "lvl": "target", "msg": "[    2.737113] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-10-28T13:11:05.319174", "lvl": "target", "msg": "[    2.743537] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.319965", "lvl": "target", "msg": "[    2.747283] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.320725", "lvl": "target", "msg": "[    2.751434] ehci-orion: EHCI orion driver"}
- {"dt": "2024-10-28T13:11:05.321553", "lvl": "target", "msg": "[    2.755644] ehci-exynos: EHCI EXYNOS driver"}
- {"dt": "2024-10-28T13:11:05.322308", "lvl": "target", "msg": "[    2.759906] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-10-28T13:11:05.323058", "lvl": "target", "msg": "[    2.766072] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-10-28T13:11:05.323796", "lvl": "target", "msg": "[    2.770533] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-10-28T13:11:05.325436", "lvl": "target", "msg": "[    2.775888] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-10-28T13:11:05.344603", "lvl": "target", "msg": "[    2.782577] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2024-10-28T13:11:05.347772", "lvl": "target", "msg": "[    2.790413] ohci-platform ee080000.usb: irq 175, io mem 0xee080000"}
- {"dt": "2024-10-28T13:11:05.460438", "lvl": "target", "msg": "[    2.884102] hub 5-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.461528", "lvl": "target", "msg": "[    2.887849] hub 5-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.462460", "lvl": "target", "msg": "[    2.891980] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-10-28T13:11:05.463371", "lvl": "target", "msg": "[    2.898658] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2024-10-28T13:11:05.465933", "lvl": "target", "msg": "[    2.906505] ohci-platform ee0a0000.usb: irq 176, io mem 0xee0a0000"}
- {"dt": "2024-10-28T13:11:05.576221", "lvl": "target", "msg": "[    3.000089] hub 6-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.577607", "lvl": "target", "msg": "[    3.003843] hub 6-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.578795", "lvl": "target", "msg": "[    3.007973] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-10-28T13:11:05.579963", "lvl": "target", "msg": "[    3.014652] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2024-10-28T13:11:05.583176", "lvl": "target", "msg": "[    3.022482] ohci-platform ee0c0000.usb: irq 177, io mem 0xee0c0000"}
- {"dt": "2024-10-28T13:11:05.691687", "lvl": "target", "msg": "[    3.116079] hub 7-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.694696", "lvl": "target", "msg": "[    3.119824] hub 7-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.695812", "lvl": "target", "msg": "[    3.123941] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-10-28T13:11:05.696837", "lvl": "target", "msg": "[    3.130618] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2024-10-28T13:11:05.698116", "lvl": "target", "msg": "[    3.138447] ohci-platform ee0e0000.usb: irq 178, io mem 0xee0e0000"}
- {"dt": "2024-10-28T13:11:05.803417", "lvl": "target", "msg": "[    3.232072] hub 8-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.807232", "lvl": "target", "msg": "[    3.235817] hub 8-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.808658", "lvl": "target", "msg": "[    3.239981] ohci-exynos: OHCI EXYNOS driver"}
- {"dt": "2024-10-28T13:11:05.810111", "lvl": "target", "msg": "[    3.244752] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.811460", "lvl": "target", "msg": "[    3.249964] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2024-10-28T13:11:05.852479", "lvl": "target", "msg": "[    3.273182] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2024-10-28T13:11:05.854039", "lvl": "target", "msg": "[    3.282294] xhci-hcd ee000000.usb: irq 173, io mem 0xee000000"}
- {"dt": "2024-10-28T13:11:05.855421", "lvl": "target", "msg": "[    3.288431] hub 9-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.856770", "lvl": "target", "msg": "[    3.292179] hub 9-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.858171", "lvl": "target", "msg": "[    3.296216] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-10-28T13:11:05.859503", "lvl": "target", "msg": "[    3.301423] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2024-10-28T13:11:05.861054", "lvl": "target", "msg": "[    3.308872] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-10-28T13:11:05.896968", "lvl": "target", "msg": "[    3.315142] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2024-10-28T13:11:05.897768", "lvl": "target", "msg": "[    3.323516] hub 10-0:1.0: USB hub found"}
- {"dt": "2024-10-28T13:11:05.898488", "lvl": "target", "msg": "[    3.327348] hub 10-0:1.0: 1 port detected"}
- {"dt": "2024-10-28T13:11:05.899182", "lvl": "target", "msg": "[    3.331927] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-10-28T13:11:05.899875", "lvl": "target", "msg": "[    3.337963] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-10-28T13:11:05.900570", "lvl": "target", "msg": "[    3.344478] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-10-28T13:11:05.901309", "lvl": "target", "msg": "[    3.351978] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2024-10-28T13:11:05.953941", "lvl": "target", "msg": "[    3.357679] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2024-10-28T13:11:05.954986", "lvl": "target", "msg": "[    3.362857] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2024-10-28T13:11:05.957573", "lvl": "target", "msg": "[    3.367449] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2024-10-28T13:11:05.958575", "lvl": "target", "msg": "[    3.373146] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2024-10-28T13:11:05.959498", "lvl": "target", "msg": "[    3.378317] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2024-10-28T13:11:05.960417", "lvl": "target", "msg": "[    3.383586] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2024-10-28T13:11:05.961386", "lvl": "target", "msg": "[    3.390742] i2c /dev entries driver"}
- {"dt": "2024-10-28T13:11:05.962287", "lvl": "target", "msg": "[    3.403460] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2024-10-28T13:11:05.963190", "lvl": "target", "msg": "[    3.407677] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2024-10-28T13:11:05.964263", "lvl": "target", "msg": "[    3.412091] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2024-10-28T13:11:06.003802", "lvl": "target", "msg": "[    3.418098] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2024-10-28T13:11:06.005896", "lvl": "target", "msg": "[    3.422481] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2024-10-28T13:11:06.006676", "lvl": "target", "msg": "[    3.427036] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-10-28T13:11:06.007381", "lvl": "target", "msg": "[    3.433510] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2024-10-28T13:11:06.008090", "lvl": "target", "msg": "[    3.442434] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2024-10-28T13:11:06.008946", "lvl": "target", "msg": "[    3.452585] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2024-10-28T13:11:06.023048", "lvl": "target", "msg": "[    3.477576] random: fast init done"}
- {"dt": "2024-10-28T13:11:06.124276", "lvl": "target", "msg": "[    3.551109] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2024-10-28T13:11:06.125490", "lvl": "target", "msg": "[    3.560046] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2024-10-28T13:11:06.126715", "lvl": "target", "msg": "[    3.570187] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2024-10-28T13:11:06.186991", "lvl": "target", "msg": "[    3.629121] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2024-10-28T13:11:06.249103", "lvl": "target", "msg": "[    3.668445] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2024-10-28T13:11:06.252980", "lvl": "target", "msg": "[    3.673415] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2024-10-28T13:11:06.254438", "lvl": "target", "msg": "[    3.681569] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2024-10-28T13:11:06.255794", "lvl": "target", "msg": "[    3.687615] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2024-10-28T13:11:06.257198", "lvl": "target", "msg": "[    3.693658] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2024-10-28T13:11:06.258538", "lvl": "target", "msg": "[    3.699693] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2024-10-28T13:11:06.260107", "lvl": "target", "msg": "[    3.705740] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2024-10-28T13:11:06.311297", "lvl": "target", "msg": "[    3.711792] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2024-10-28T13:11:06.313257", "lvl": "target", "msg": "[    3.717831] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2024-10-28T13:11:06.313991", "lvl": "target", "msg": "[    3.723870] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2024-10-28T13:11:06.314651", "lvl": "target", "msg": "[    3.731948] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2024-10-28T13:11:06.315296", "lvl": "target", "msg": "[    3.744232] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2024-10-28T13:11:06.315947", "lvl": "target", "msg": "[    3.755411] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2024-10-28T13:11:06.316706", "lvl": "target", "msg": "[    3.766517] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2024-10-28T13:11:06.348994", "lvl": "target", "msg": "[    3.777037] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-10-28T13:11:06.351607", "lvl": "target", "msg": "[    3.783202] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-10-28T13:11:06.352644", "lvl": "target", "msg": "[    3.788600] renesas_sdhi_internal_dmac ee140000.sd: Using IPMMU context 1"}
- {"dt": "2024-10-28T13:11:06.353813", "lvl": "target", "msg": "[    3.795382] renesas_sdhi_internal_dmac ee140000.sd: Adding to iommu group 0"}
- {"dt": "2024-10-28T13:11:06.436661", "lvl": "target", "msg": "[    3.851108] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz"}
- {"dt": "2024-10-28T13:11:06.437999", "lvl": "target", "msg": "[    3.860886] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-10-28T13:11:06.439177", "lvl": "target", "msg": "[    3.867921] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-10-28T13:11:06.440336", "lvl": "target", "msg": "[    3.875571] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-10-28T13:11:06.441556", "lvl": "target", "msg": "[    3.882804] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-10-28T13:11:06.442708", "lvl": "target", "msg": "[    3.888350] usbhid: USB HID core driver"}
- {"dt": "2024-10-28T13:11:06.452720", "lvl": "target", "msg": "[    3.893895] irq: type mismatch, failed to map hwirq-39 for interrupt-controller@f1010000!"}
- {"dt": "2024-10-28T13:11:06.526732", "lvl": "target", "msg": "[    3.948077] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2024-10-28T13:11:06.528726", "lvl": "target", "msg": "[    3.953952] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2024-10-28T13:11:06.529525", "lvl": "target", "msg": "[    3.958862] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2024-10-28T13:11:06.530188", "lvl": "target", "msg": "[    3.965172] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2024-10-28T13:11:06.530970", "lvl": "target", "msg": "[    3.971266] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (234:0)"}
- {"dt": "2024-10-28T13:11:06.531644", "lvl": "target", "msg": "[    3.980136]  mmcblk0: p1 p2"}
- {"dt": "2024-10-28T13:11:06.615381", "lvl": "target", "msg": "[    4.042787] optee: probing for conduit method from DT."}
- {"dt": "2024-10-28T13:11:06.618205", "lvl": "target", "msg": "[    4.047925] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2024-10-28T13:11:06.619239", "lvl": "target", "msg": "[    4.048545] optee driver R-Car Rev.1.0.11"}
- {"dt": "2024-10-28T13:11:06.620177", "lvl": "target", "msg": "[    4.056885] optee: initialized driver"}
- {"dt": "2024-10-28T13:11:06.621309", "lvl": "target", "msg": "[    4.060861] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-10-28T13:11:06.658197", "lvl": "target", "msg": "[    4.079397] rcar_sound ec500000.sound: probed"}
- {"dt": "2024-10-28T13:11:06.661271", "lvl": "target", "msg": "[    4.083983] drop_monitor: Initializing network drop monitor service"}
- {"dt": "2024-10-28T13:11:06.662407", "lvl": "target", "msg": "[    4.090357] NET: Registered protocol family 17"}
- {"dt": "2024-10-28T13:11:06.663543", "lvl": "target", "msg": "[    4.094893] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-10-28T13:11:06.664666", "lvl": "target", "msg": "[    4.099185] Key type dns_resolver registered"}
- {"dt": "2024-10-28T13:11:06.665831", "lvl": "target", "msg": "[    4.103780] registered taskstats version 1"}
- {"dt": "2024-10-28T13:11:06.667327", "lvl": "target", "msg": "[    4.107861] Loading compiled-in X.509 certificates"}
- {"dt": "2024-10-28T13:11:06.705336", "lvl": "target", "msg": "[    4.123341] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2024-10-28T13:11:06.706020", "lvl": "target", "msg": "[    4.129252] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2024-10-28T13:11:06.706553", "lvl": "target", "msg": "[    4.134452] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2024-10-28T13:11:06.707905", "lvl": "target", "msg": "[    4.140072] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2024-10-28T13:11:06.708510", "lvl": "target", "msg": "[    4.148506] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-10-28T13:11:06.709179", "lvl": "target", "msg": "[    4.155100] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-10-28T13:11:06.709777", "lvl": "target", "msg": "[    4.162078] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2024-10-28T13:11:06.723059", "lvl": "target", "msg": "[    4.169790] [drm] Device feb00000.display probed"}
- {"dt": "2024-10-28T13:11:07.109113", "lvl": "target", "msg": "[    4.522378] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-10-28T13:11:07.109776", "lvl": "target", "msg": "[    4.544810] rcar-du feb00000.display: fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2024-10-28T13:11:07.111876", "lvl": "target", "msg": "[    4.555525] bd9571mwv 7-0030: Device: BD9571MWV rev. 4"}
- {"dt": "2024-10-28T13:11:07.179933", "lvl": "target", "msg": "[    4.621873] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz"}
- {"dt": "2024-10-28T13:11:07.259816", "lvl": "target", "msg": "[    4.679879] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 200 MHz"}
- {"dt": "2024-10-28T13:11:07.260533", "lvl": "target", "msg": "[    4.690111] hdmi-audio-codec hdmi-audio-codec.5.auto: ASoC: sink widget TX overwritten"}
- {"dt": "2024-10-28T13:11:07.261133", "lvl": "target", "msg": "[    4.698064] asoc-audio-graph-card sound: ak4613-hifi <-> rsnd-dai.0 mapping ok"}
- {"dt": "2024-10-28T13:11:07.261756", "lvl": "target", "msg": "[    4.705481] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.1 mapping ok"}
- {"dt": "2024-10-28T13:11:07.262259", "lvl": "target", "msg": "[    4.712524] asoc-audio-graph-card sound: i2s-hifi <-> rsnd-dai.2 mapping ok"}
- {"dt": "2024-10-28T13:11:07.283673", "lvl": "target", "msg": "[    4.719545] asoc-audio-graph-card sound: ASoC: no DMI vendor name!"}
- {"dt": "2024-10-28T13:11:07.287089", "lvl": "target", "msg": "[    4.729726] hctosys: unable to open rtc device (rtc0)"}
- {"dt": "2024-10-28T13:11:07.376929", "lvl": "target", "msg": "[    4.808427] 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-10-28T13:11:13.986223", "lvl": "target", "msg": "[   11.426178] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-10-28T13:11:17.002883", "lvl": "target", "msg": "[   11.453116] Sending DHCP requests .., OK"}
- {"dt": "2024-10-28T13:11:17.007123", "lvl": "target", "msg": "[   14.425033] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.28"}
- {"dt": "2024-10-28T13:11:17.008810", "lvl": "target", "msg": "[   14.432829] IP-Config: Complete:"}
- {"dt": "2024-10-28T13:11:17.010462", "lvl": "target", "msg": "[   14.436042]      device=eth0, hwaddr=00:80:45:1b:40:7f, ipaddr=192.168.66.28, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-10-28T13:11:17.012068", "lvl": "target", "msg": "[   14.446251]      host=192.168.66.28, domain=, nis-domain=(none)"}
- {"dt": "2024-10-28T13:11:17.013753", "lvl": "target", "msg": "[   14.452138]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2024-10-28T13:11:17.015484", "lvl": "target", "msg": "[   14.452140]      nameserver0=10.1.1.1"}
- {"dt": "2024-10-28T13:11:17.037275", "lvl": "target", "msg": "[   14.462278] ALSA device list:"}
- {"dt": "2024-10-28T13:11:17.039077", "lvl": "target", "msg": "[   14.465235]   #0: rcar-sound"}
- {"dt": "2024-10-28T13:11:17.040603", "lvl": "target", "msg": "[   14.471527] Freeing unused kernel memory: 6080K"}
- {"dt": "2024-10-28T13:11:17.044727", "lvl": "target", "msg": "[   14.481216] Run /init as init process"}
- {"dt": "2024-10-28T13:11:17.099981", "lvl": "target", "msg": "Starting logging: OK"}
- {"dt": "2024-10-28T13:11:17.100609", "lvl": "target", "msg": "Populating /dev using udev: [   14.513966] udevd[316]: starting version 3.2.5"}
- {"dt": "2024-10-28T13:11:17.102770", "lvl": "target", "msg": "[   14.518722] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-10-28T13:11:17.103751", "lvl": "target", "msg": "[   14.525267] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-10-28T13:11:17.104834", "lvl": "target", "msg": "[   14.531714] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-10-28T13:11:17.105950", "lvl": "target", "msg": "[   14.542116] udevd[317]: starting eudev-3.2.5"}
- {"dt": "2024-10-28T13:11:17.335651", "lvl": "target", "msg": "done"}
- {"dt": "2024-10-28T13:11:17.336957", "lvl": "target", "msg": "Initializing random number generator... done."}
- {"dt": "2024-10-28T13:11:17.339982", "lvl": "target", "msg": "Starting network: ip: RTNETLINK answers: File exists"}
- {"dt": "2024-10-28T13:11:17.341307", "lvl": "target", "msg": "FAIL"}
- {"dt": "2024-10-28T13:11:17.353726", "lvl": "target", "msg": "Starting dropbear sshd: OK"}
- {"dt": "2024-10-28T13:11:17.369700", "lvl": "target", "msg": "/bin/sh: can't access tty; job control turned off"}
- {"dt": "2024-10-28T13:11:17.372111", "lvl": "debug", "msg": "Matched prompt #12: / #"}
- {"dt": "2024-10-28T13:11:17.373428", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "13.75", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:11:17.375617", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2024-10-28T13:11:17.377023", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:14) [common]"}
- {"dt": "2024-10-28T13:11:17.378188", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "13.76", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:11:17.380599", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:15) [common]"}
- {"dt": "2024-10-28T13:11:17.381641", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:47) [common]"}
- {"dt": "2024-10-28T13:11:17.382325", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:50) [common]"}
- {"dt": "2024-10-28T13:11:17.382993", "lvl": "info", "msg": "start: 3 finalize (timeout 00:30:00) [common]"}
- {"dt": "2024-10-28T13:11:17.383654", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2024-10-28T13:11:17.384628", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2024-10-28T13:11:20.716837", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-10-28T13:11:20.760235", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2024-10-28T13:11:20.862699", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:03) [common]"}
- {"dt": "2024-10-28T13:11:20.864696", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.48", "extra": {"status": "success"}, "level": "3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-10-28T13:11:20.868682", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:29:57) [common]"}
- {"dt": "2024-10-28T13:11:21.872738", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-10-28T13:11:21.875053", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-10-28T13:11:21.876919", "lvl": "target", "msg": "/ # "}
- {"dt": "2024-10-28T13:11:21.979864", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-10-28T13:11:21.982304", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:05) [common]"}
- {"dt": "2024-10-28T13:11:21.984678", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-10-28T13:11:21.987005", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/ramdisk"}
- {"dt": "2024-10-28T13:11:21.994452", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/kernel"}
- {"dt": "2024-10-28T13:11:22.005703", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37339/tftp-deploy-qdg826re/dtb"}
- {"dt": "2024-10-28T13:11:22.007253", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/37339"}
- {"dt": "2024-10-28T13:11:22.061925", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/37339"}
- {"dt": "2024-10-28T13:11:22.062870", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-10-28T13:11:22.063424", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
