- {"dt": "2024-11-30T09:51:40.601927", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-11-30T09:51:40.602193", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-11-30T09:51:40.602350", "lvl": "info", "msg": "Start time: 2024-11-30 09:51:40.602339+00:00 (UTC)"}
- {"dt": "2024-11-30T09:51:40.602532", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-8-gd700ebb99e8f/arm64/base/rootfs.cpio.gz?raw=true exists"}
- {"dt": "2024-11-30T09:51:41.991569", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/Image?raw=true exists"}
- {"dt": "2024-11-30T09:51:44.461546", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/dtbs/renesas/r8a7795-h3ulcb-kf.dtb?raw=true exists"}
- {"dt": "2024-11-30T09:51:45.094397", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/modules.tar.xz?raw=true exists"}
- {"dt": "2024-11-30T09:51:45.978385", "lvl": "info", "msg": "validate duration: 5.38"}
- {"dt": "2024-11-30T09:51:45.978633", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-11-30T09:51:45.978879", "lvl": "info", "msg": "start: 1 tftp-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-11-30T09:51:45.979035", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-11-30T09:51:45.979166", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-11-30T09:51:45.979381", "lvl": "debug", "msg": "Not decompressing ramdisk as can be used compressed."}
- {"dt": "2024-11-30T09:51:45.979520", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-8-gd700ebb99e8f/arm64/base/rootfs.cpio.gz?raw=true"}
- {"dt": "2024-11-30T09:51:45.979619", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/ramdisk/rootfs.cpio.gz"}
- {"dt": "2024-11-30T09:51:45.979711", "lvl": "debug", "msg": "total size: 4192802 (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.413064", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.438759", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.451983", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.459504", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.466766", "lvl": "debug", "msg": "progress  20\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.470598", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.474059", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.476339", "lvl": "debug", "msg": "progress  35\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.481386", "lvl": "debug", "msg": "progress  40\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.483755", "lvl": "debug", "msg": "progress  45\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.487463", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.489723", "lvl": "debug", "msg": "progress  55\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.493526", "lvl": "debug", "msg": "progress  60\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.499897", "lvl": "debug", "msg": "progress  65\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.505605", "lvl": "debug", "msg": "progress  70\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.514332", "lvl": "debug", "msg": "progress  75\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.516430", "lvl": "debug", "msg": "progress  80\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.517895", "lvl": "debug", "msg": "progress  85\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.519836", "lvl": "debug", "msg": "progress  90\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.521282", "lvl": "debug", "msg": "progress  95\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.523810", "lvl": "debug", "msg": "progress 100\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.524234", "lvl": "info", "msg": "3\u202FMB downloaded in 0.54\u202Fs (7.34\u202FMB/s)"}
- {"dt": "2024-11-30T09:51:46.524478", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:46.524598", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.55", "extra": {"label": "ramdisk", "md5sum": "5687979ea3779437574a3542f9d6d7c7", "sha256sum": "fba442c1ce42fc5c7a5175fbfaaa2450a6152cf2ab3392f2809d7c27991b27b3", "sha512sum": "ac8914b7dceec968337487762735b1cee4ee1891c8533347f0a2ceaf29b948727a422662fdf3af3a42fec6770427e56b1da84e131e0e80aa107d3aebc8b508c3", "size": ! "4192802"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:46.524850", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:46.524971", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-11-30T09:51:46.525081", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-11-30T09:51:46.525326", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/Image?raw=true"}
- {"dt": "2024-11-30T09:51:46.525427", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/kernel/Image"}
- {"dt": "2024-11-30T09:51:46.525510", "lvl": "debug", "msg": "total size: 24838656 (23\u202FMB)"}
- {"dt": "2024-11-30T09:51:46.525592", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-30T09:51:47.814107", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.839900", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.866412", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.906790", "lvl": "debug", "msg": "progress  15\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.933240", "lvl": "debug", "msg": "progress  20\u202F% (4\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.943832", "lvl": "debug", "msg": "progress  25\u202F% (5\u202FMB)"}
- {"dt": "2024-11-30T09:51:47.966293", "lvl": "debug", "msg": "progress  30\u202F% (7\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.008439", "lvl": "debug", "msg": "progress  35\u202F% (8\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.034270", "lvl": "debug", "msg": "progress  40\u202F% (9\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.060631", "lvl": "debug", "msg": "progress  45\u202F% (10\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.105536", "lvl": "debug", "msg": "progress  50\u202F% (11\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.132956", "lvl": "debug", "msg": "progress  55\u202F% (13\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.164522", "lvl": "debug", "msg": "progress  60\u202F% (14\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.200942", "lvl": "debug", "msg": "progress  65\u202F% (15\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.249927", "lvl": "debug", "msg": "progress  70\u202F% (16\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.298079", "lvl": "debug", "msg": "progress  75\u202F% (17\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.346540", "lvl": "debug", "msg": "progress  80\u202F% (18\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.404187", "lvl": "debug", "msg": "progress  85\u202F% (20\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.461391", "lvl": "debug", "msg": "progress  90\u202F% (21\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.527298", "lvl": "debug", "msg": "progress  95\u202F% (22\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.590545", "lvl": "debug", "msg": "progress 100\u202F% (23\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.590814", "lvl": "info", "msg": "23\u202FMB downloaded in 2.07\u202Fs (11.47\u202FMB/s)"}
- {"dt": "2024-11-30T09:51:48.591060", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-11-30T09:51:48.591177", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.07", "extra": {"label": "kernel", "md5sum": "01b9f301d34451480c5805691308938b", "sha256sum": "12adb40beb10364fd7956f8ba0efe7bf407830d401ae7d7107edf040b653a0af", "sha512sum": "b1e1cac6ee816caf94b11220c20bee3570a7945bb71396f25e2419bbcc7f98096c9ad12bc49a91b37904190be003ec0ec2b7f4b99d9d03c2d61754bcfb70860c", "size": ! "24838656"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:48.591419", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-11-30T09:51:48.591549", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:57) [common]"}
- {"dt": "2024-11-30T09:51:48.591672", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:57) [common]"}
- {"dt": "2024-11-30T09:51:48.591913", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/dtbs/renesas/r8a7795-h3ulcb-kf.dtb?raw=true"}
- {"dt": "2024-11-30T09:51:48.592024", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb"}
- {"dt": "2024-11-30T09:51:48.592112", "lvl": "debug", "msg": "total size: 69103 (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.592201", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-30T09:51:48.854294", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.855278", "lvl": "debug", "msg": "progress  94\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.855632", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.855809", "lvl": "info", "msg": "0\u202FMB downloaded in 0.26\u202Fs (0.25\u202FMB/s)"}
- {"dt": "2024-11-30T09:51:48.856023", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:48.856132", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.26", "extra": {"label": "dtb", "md5sum": "e1ed5334e2025b4825b4788d24a95e66", "sha256sum": "ce57ea011e9d32d0387dfda30907c0037998f70803dcdcb9f3703a3a2ce69d57", "sha512sum": "72c37afe2d5b593b8dd523e712ec665ddbe5c550b1a22b9737147ec221f561f009cfd0a5caaa85a46c3afd8b3f6ebd4d2980dc714f2061f2f4b01b991143d860", "size": ! "69103"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:48.856360", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:48.856479", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:57) [common]"}
- {"dt": "2024-11-30T09:51:48.856595", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:57) [common]"}
- {"dt": "2024-11-30T09:51:48.856853", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/modules.tar.xz?raw=true"}
- {"dt": "2024-11-30T09:51:48.856965", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/modules/modules.tar"}
- {"dt": "2024-11-30T09:51:48.857051", "lvl": "debug", "msg": "total size: 5900632 (5\u202FMB)"}
- {"dt": "2024-11-30T09:51:48.857139", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-11-30T09:51:49.331635", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.350620", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.376460", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.415006", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.447774", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.480635", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.511949", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.543066", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.576899", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.611149", "lvl": "debug", "msg": "progress  45\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.641659", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.674543", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.704911", "lvl": "debug", "msg": "progress  60\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.732882", "lvl": "debug", "msg": "progress  65\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.760239", "lvl": "debug", "msg": "progress  70\u202F% (3\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.794057", "lvl": "debug", "msg": "progress  75\u202F% (4\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.819593", "lvl": "debug", "msg": "progress  80\u202F% (4\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.845880", "lvl": "debug", "msg": "progress  85\u202F% (4\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.877776", "lvl": "debug", "msg": "progress  90\u202F% (5\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.909052", "lvl": "debug", "msg": "progress  95\u202F% (5\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.936082", "lvl": "debug", "msg": "progress 100\u202F% (5\u202FMB)"}
- {"dt": "2024-11-30T09:51:49.942922", "lvl": "info", "msg": "5\u202FMB downloaded in 1.09\u202Fs (5.18\u202FMB/s)"}
- {"dt": "2024-11-30T09:51:49.943220", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:49.943337", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.09", "extra": {"label": "modules", "md5sum": "6ae27fb4240ed9f3cda4de91dfc1c42f", "sha256sum": "da3e4365eb778cd0aefed5b97b3f829a7477a6f9a20420c4f87e46c081ae4a0d", "sha512sum": "be7f99e4adf220de040e9cb4e054c741bc2ffa10da992ade8fcc4be89a097f8fe17433d3717bf407794e786cdccf12f886d269a95438c54e38a064702ac37987", "size": ! "5900632"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:49.943583", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:49.943715", "lvl": "debug", "msg": "start: 1.5 prepare-tftp-overlay (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:49.943840", "lvl": "debug", "msg": "start: 1.5.1 extract-nfsrootfs (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:49.943956", "lvl": "debug", "msg": "end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:49.944077", "lvl": "debug", "msg": "start: 1.5.2 lava-overlay (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:49.944183", "lvl": "info", "msg": "[common] skipped lava-overlay - no test action."}
- {"dt": "2024-11-30T09:51:49.944296", "lvl": "debug", "msg": "end: 1.5.2 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:49.944410", "lvl": "debug", "msg": "start: 1.5.3 extract-overlay-ramdisk (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.138378", "lvl": "debug", "msg": "end: 1.5.3 extract-overlay-ramdisk (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.138604", "lvl": "debug", "msg": "start: 1.5.4 extract-modules (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.138730", "lvl": "info", "msg": "extracting modules file /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/modules/modules.tar to /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk"}
- {"dt": "2024-11-30T09:51:50.404325", "lvl": "debug", "msg": "end: 1.5.4 extract-modules (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.404639", "lvl": "debug", "msg": "start: 1.5.5 apply-overlay-tftp (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.405019", "lvl": "debug", "msg": "end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.405208", "lvl": "debug", "msg": "start: 1.5.6 prepare-kernel (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.405381", "lvl": "debug", "msg": "start: 1.5.6.1 uboot-prepare-kernel (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.405556", "lvl": "debug", "msg": "end: 1.5.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.405720", "lvl": "debug", "msg": "end: 1.5.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.405885", "lvl": "debug", "msg": "start: 1.5.7 configure-preseed-file (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.406052", "lvl": "debug", "msg": "end: 1.5.7 configure-preseed-file (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:50.406230", "lvl": "debug", "msg": "start: 1.5.8 compress-ramdisk (timeout 00:09:56) [common]"}
- {"dt": "2024-11-30T09:51:50.406386", "lvl": "info", "msg": "Building ramdisk /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk"}
- {"dt": "2024-11-30T09:51:50.766618", "lvl": "debug", "msg": ">> 85384 blocks\n"}
- {"dt": "2024-11-30T09:51:53.158852", "lvl": "debug", "msg": "Adding RAMdisk u-boot header."}
- {"dt": "2024-11-30T09:51:53.159036", "lvl": "debug", "msg": "mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:51:53.260589", "lvl": "debug", "msg": "output: Image Name:   "}
- {"dt": "2024-11-30T09:51:53.260779", "lvl": "debug", "msg": "output: Created:      Sat Nov 30 09:51:53 2024"}
- {"dt": "2024-11-30T09:51:53.260853", "lvl": "debug", "msg": "output: Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-11-30T09:51:53.260917", "lvl": "debug", "msg": "output: Data Size:    13002429 Bytes = 12697.68 KiB = 12.40 MiB"}
- {"dt": "2024-11-30T09:51:53.260977", "lvl": "debug", "msg": "output: Load Address: 00000000"}
- {"dt": "2024-11-30T09:51:53.261033", "lvl": "debug", "msg": "output: Entry Point:  00000000"}
- {"dt": "2024-11-30T09:51:53.261089", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-11-30T09:51:53.261226", "lvl": "debug", "msg": "rename /var/lib/lava/dispatcher/tmp/38024/extract-overlay-ramdisk-5dljhz_g/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:51:53.261354", "lvl": "debug", "msg": "end: 1.5.8 compress-ramdisk (duration 00:00:03) [common]"}
- {"dt": "2024-11-30T09:51:53.261445", "lvl": "debug", "msg": "end: 1.5 prepare-tftp-overlay (duration 00:00:03) [common]"}
- {"dt": "2024-11-30T09:51:53.261529", "lvl": "debug", "msg": "start: 1.6 lxc-create-udev-rule-action (timeout 00:09:53) [common]"}
- {"dt": "2024-11-30T09:51:53.261603", "lvl": "debug", "msg": "No LXC device requested"}
- {"dt": "2024-11-30T09:51:53.261678", "lvl": "debug", "msg": "end: 1.6 lxc-create-udev-rule-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:53.261741", "lvl": "debug", "msg": "Checking files for TFTP limit of 4294967296 bytes."}
- {"dt": "2024-11-30T09:51:53.262216", "lvl": "info", "msg": "end: 1 tftp-deploy (duration 00:00:07) [common]"}
- {"dt": "2024-11-30T09:51:53.262311", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.262394", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.262470", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:53.262548", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.262673", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-11-30T09:51:53.262738", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000 0x68000000"}
- {"dt": "2024-11-30T09:51:53.262798", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2024-11-30T09:51:53.262857", "lvl": "debug", "msg": "- {DTB}: 38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb"}
- {"dt": "2024-11-30T09:51:53.262914", "lvl": "debug", "msg": "- {INITRD}: 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:51:53.262971", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2024-11-30T09:51:53.263027", "lvl": "debug", "msg": "- {KERNEL}: 38024/tftp-deploy-by6h712z/kernel/Image"}
- {"dt": "2024-11-30T09:51:53.263084", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-11-30T09:51:53.263150", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-11-30T09:51:53.263207", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-11-30T09:51:53.263263", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2024-11-30T09:51:53.263319", "lvl": "debug", "msg": "- {RAMDISK}: 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:51:53.263376", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-11-30T09:51:53.263432", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-11-30T09:51:53.263489", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-11-30T09:51:53.263545", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-11-30T09:51:53.263601", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-11-30T09:51:53.263658", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-11-30T09:51:53.263713", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-11-30T09:51:53.263769", "lvl": "info", "msg": "- setenv initrd_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:53.263825", "lvl": "info", "msg": "- setenv fdt_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:53.263880", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-11-30T09:51:53.263937", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-11-30T09:51:53.263992", "lvl": "info", "msg": "- tftp 0x68080000 38024/tftp-deploy-by6h712z/kernel/Image"}
- {"dt": "2024-11-30T09:51:53.264048", "lvl": "info", "msg": "- tftp 0x4a000000 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:51:53.264105", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-11-30T09:51:53.264160", "lvl": "info", "msg": "- tftp 0x68000000 38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb"}
- {"dt": "2024-11-30T09:51:53.264216", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-11-30T09:51:53.264272", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000 0x68000000"}
- {"dt": "2024-11-30T09:51:53.264345", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:53.264429", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.00", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:53.264588", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.264656", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2024-11-30T09:51:53.267914", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-11-30T09:51:53.268273", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:51:53.268396", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.268495", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.268614", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:05:00) [common]"}
- {"dt": "2024-11-30T09:51:53.268849", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2024-11-30T09:51:53.374294", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-11-30T09:51:54.438154", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-11-30T09:51:54.448659", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-11-30T09:51:54.549464", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:54.549983", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "1.28", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:51:54.550707", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:51:54.551516", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:04:59) [common]"}
- {"dt": "2024-11-30T09:51:54.551819", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-11-30T09:51:54.552041", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:05:00)"}
- {"dt": "2024-11-30T09:51:54.553002", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-11-30T09:51:54.553207", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-11-30T09:51:54.553378", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-11-30T09:51:54.621389", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Lo\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.621568", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.624446", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.674265", "lvl": "target", "msg": "NOTICE:  B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.674487", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.674583", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.677403", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2024-11-30T09:51:54.717555", "lvl": "target", "msg": "NOTICE:  BL2: LCM state \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.763080", "lvl": "target", "msg": "NOTICE:  BL2: PRR is\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.763554", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.765973", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.814950", "lvl": "target", "msg": "NOTICE: \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.815142", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.815215", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.873903", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80M\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.874115", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.874211", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.874296", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2024-11-30T09:51:54.874375", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-11-30T09:51:54.941593", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetV\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0"}
- {"dt": "2024-11-30T09:51:54.941788", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-11-30T09:51:54.941884", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2024-11-30T09:51:54.941970", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2024-11-30T09:51:54.942045", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-11-30T09:51:54.942122", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-11-30T09:51:54.942194", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2024-11-30T09:51:54.944707", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-11-30T09:51:54.988314", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-11-30T09:51:54.988527", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-11-30T09:51:54.988631", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-11-30T09:51:54.988718", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-11-30T09:51:54.988811", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 47fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:54.988885", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 57fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:54.988957", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 67fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:54.989027", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 77fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:54.989100", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f78"}
- {"dt": "2024-11-30T09:51:54.991479", "lvl": "target", "msg": "NOTICE:  BL2: v2.9(release):9cdb21f75-dirty"}
- {"dt": "2024-11-30T09:51:54.991573", "lvl": "target", "msg": "NOTICE:  BL2: Built : 14:13:42, Oct 24 2024"}
- {"dt": "2024-11-30T09:51:55.032093", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2024-11-30T09:51:55.032674", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2024-11-30T09:51:55.033075", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2024-11-30T09:51:55.033366", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2024-11-30T09:51:55.033652", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2024-11-30T09:51:55.033917", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2024-11-30T09:51:55.035235", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2024-11-30T09:51:55.074599", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2024-11-30T09:51:55.074863", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2024-11-30T09:51:55.077640", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-11-30T09:51:55.631125", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:51:55.631323", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:51:55.631419", "lvl": "target", "msg": "U-Boot 2020.10 (Oct 24 2024 - 14:13:42 +0000)"}
- {"dt": "2024-11-30T09:51:55.631503", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:51:55.631581", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-11-30T09:51:55.634193", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2024-11-30T09:51:55.826216", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-11-30T09:51:55.826446", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2024-11-30T09:51:55.850064", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-11-30T09:51:55.850240", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:55.850331", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:55.853122", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-11-30T09:51:55.853242", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:51:55.901144", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2024-11-30T09:51:56.064988", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-11-30T09:51:56.065212", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-11-30T09:51:56.065308", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-11-30T09:51:56.067996", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-11-30T09:51:56.116373", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-11-30T09:51:56.116808", "lvl": "input", "msg": "\n"}
- {"dt": "2024-11-30T09:51:56.167057", "lvl": "target", "msg": "Hit any key to stop autoboot:"}
- {"dt": "2024-11-30T09:51:56.167421", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2024-11-30T09:51:56.167621", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:04:57) [common]"}
- {"dt": "2024-11-30T09:51:56.167737", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2024-11-30T09:51:56.167842", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:04:57)"}
- {"dt": "2024-11-30T09:51:56.179047", "lvl": "target", "msg": "  3 \b\b\b 0 "}
- {"dt": "2024-11-30T09:51:56.179515", "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-11-30T09:51:56.179644", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-11-30T09:51:56.279992", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2024-11-30T09:51:56.280296", "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:04:57)"}
- {"dt": "2024-11-30T09:51:56.291857", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-11-30T09:51:56.292174", "lvl": "input", "msg": "setenv initrd_high 0xffffffffffffffff\n"}
- {"dt": "2024-11-30T09:51:56.392661", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:56.393005", "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:04:57)"}
- {"dt": "2024-11-30T09:51:56.403870", "lvl": "target", "msg": "setenv initrd_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:56.404312", "lvl": "input", "msg": "setenv fdt_high 0xffffffffffffffff\n"}
- {"dt": "2024-11-30T09:51:56.504786", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:56.505221", "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:04:57)"}
- {"dt": "2024-11-30T09:51:56.515689", "lvl": "target", "msg": "setenv fdt_high 0xffffffffffffffff"}
- {"dt": "2024-11-30T09:51:56.516017", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-11-30T09:51:56.616380", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2024-11-30T09:51:56.616703", "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:04:57)"}
- {"dt": "2024-11-30T09:51:56.639782", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-11-30T09:51:59.775557", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2024-11-30T09:51:59.775745", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-11-30T09:52:00.032084", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-11-30T09:52:00.527450", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-11-30T09:52:00.783237", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.15 (1012 ms)"}
- {"dt": "2024-11-30T09:52:00.784094", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-11-30T09:52:00.885025", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2024-11-30T09:52:00.885761", "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:04:52)"}
- {"dt": "2024-11-30T09:52:00.895067", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-11-30T09:52:00.895874", "lvl": "input", "msg": "tftp 0x68080000 38024/tftp-deploy-by6h712z/kernel/Image\n"}
- {"dt": "2024-11-30T09:52:00.996933", "lvl": "target", "msg": "=> tftp 0x68080000 38024/tftp-deploy-by6h712z/kernel/Image"}
- {"dt": "2024-11-30T09:52:00.997659", "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:04:52)"}
- {"dt": "2024-11-30T09:52:01.007115", "lvl": "target", "msg": "tftp 0x68080000 38024/tftp-deploy-by6h712z/kernel/Image"}
- {"dt": "2024-11-30T09:52:01.030614", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-11-30T09:52:01.031157", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.15"}
- {"dt": "2024-11-30T09:52:01.031477", "lvl": "target", "msg": "Filename '38024/tftp-deploy-by6h712z/kernel/Image'."}
- {"dt": "2024-11-30T09:52:01.033730", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2024-11-30T09:52:01.305466", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-11-30T09:52:01.593197", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:01.865133", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:02.153000", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:02.424450", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:02.680220", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:02.920055", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:03.176014", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:03.415798", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:03.671883", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:03.911690", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:04.167383", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:04.407316", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:04.647173", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:04.919011", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:05.190823", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:05.511194", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:05.782643", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:06.070643", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:06.358360", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:06.646166", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:06.934014", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:07.221965", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:07.526152", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:07.846039", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:08.166784", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:08.167328", "lvl": "target", "msg": "\t ###"}
- {"dt": "2024-11-30T09:52:08.167650", "lvl": "target", "msg": "\t 3.3 MiB/s"}
- {"dt": "2024-11-30T09:52:08.182002", "lvl": "target", "msg": "done"}
- {"dt": "2024-11-30T09:52:08.182468", "lvl": "target", "msg": "Bytes transferred = 24838656 (17b0200 hex)"}
- {"dt": "2024-11-30T09:52:08.183166", "lvl": "input", "msg": "tftp 0x4a000000 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot\n"}
- {"dt": "2024-11-30T09:52:08.283781", "lvl": "target", "msg": "=> tftp 0x4a000000 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:52:08.284479", "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:04:45)"}
- {"dt": "2024-11-30T09:52:08.291048", "lvl": "target", "msg": "tftp 0x4a000000 38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-11-30T09:52:08.332947", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-11-30T09:52:08.333157", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.15"}
- {"dt": "2024-11-30T09:52:08.333257", "lvl": "target", "msg": "Filename '38024/tftp-deploy-by6h712z/ramdisk/ramdisk.cpio.gz.uboot'."}
- {"dt": "2024-11-30T09:52:08.333346", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2024-11-30T09:52:08.611381", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-11-30T09:52:08.899260", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:09.187030", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:09.490976", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:09.778780", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:10.082615", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:10.370425", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:10.658308", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:10.962097", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:11.250014", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:11.537457", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:11.825235", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:12.112875", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-11-30T09:52:12.288572", "lvl": "target", "msg": "\t #########################################"}
- {"dt": "2024-11-30T09:52:12.288770", "lvl": "target", "msg": "\t 3.1 MiB/s"}
- {"dt": "2024-11-30T09:52:12.305735", "lvl": "target", "msg": "done"}
- {"dt": "2024-11-30T09:52:12.305928", "lvl": "target", "msg": "Bytes transferred = 13002493 (c666fd hex)"}
- {"dt": "2024-11-30T09:52:12.306238", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-11-30T09:52:12.406508", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2024-11-30T09:52:12.406770", "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:04:41)"}
- {"dt": "2024-11-30T09:52:12.417450", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-11-30T09:52:12.417806", "lvl": "input", "msg": "tftp 0x68000000 38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb\n"}
- {"dt": "2024-11-30T09:52:12.518095", "lvl": "target", "msg": "=> tftp 0x68000000 38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb"}
- {"dt": "2024-11-30T09:52:12.518467", "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:04:41)"}
- {"dt": "2024-11-30T09:52:12.524580", "lvl": "target", "msg": "tftp 0x68000000 38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb"}
- {"dt": "2024-11-30T09:52:12.564835", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-11-30T09:52:12.565024", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.15"}
- {"dt": "2024-11-30T09:52:12.565134", "lvl": "target", "msg": "Filename '38024/tftp-deploy-by6h712z/dtb/r8a7795-h3ulcb-kf.dtb'."}
- {"dt": "2024-11-30T09:52:12.565229", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2024-11-30T09:52:12.585510", "lvl": "target", "msg": "Loading: *\b#####"}
- {"dt": "2024-11-30T09:52:12.585708", "lvl": "target", "msg": "\t 3 MiB/s"}
- {"dt": "2024-11-30T09:52:12.585801", "lvl": "target", "msg": "done"}
- {"dt": "2024-11-30T09:52:12.585882", "lvl": "target", "msg": "Bytes transferred = 69103 (10def hex)"}
- {"dt": "2024-11-30T09:52:12.586151", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'\n"}
- {"dt": "2024-11-30T09:52:12.686417", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-11-30T09:52:12.686700", "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:04:41)"}
- {"dt": "2024-11-30T09:52:12.691551", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-11-30T09:52:12.691874", "lvl": "input", "msg": "booti 0x68080000 0x4a000000 0x68000000\n"}
- {"dt": "2024-11-30T09:52:12.792148", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000 0x68000000"}
- {"dt": "2024-11-30T09:52:12.792440", "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-11-30T09:52:12.792602", "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:04:40)"}
- {"dt": "2024-11-30T09:52:12.830342", "lvl": "target", "msg": "booti 0x68080000 0x4a000000 0x68000000"}
- {"dt": "2024-11-30T09:52:12.830551", "lvl": "target", "msg": "## Loading init Ramdisk from Legacy Image at 4a000000 ..."}
- {"dt": "2024-11-30T09:52:12.872852", "lvl": "target", "msg": "   Image Name:   "}
- {"dt": "2024-11-30T09:52:12.873047", "lvl": "target", "msg": "   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-11-30T09:52:12.873149", "lvl": "target", "msg": "   Data Size:    13002429 Bytes = 12.4 MiB"}
- {"dt": "2024-11-30T09:52:12.873235", "lvl": "target", "msg": "   Load Address: 00000000"}
- {"dt": "2024-11-30T09:52:12.873316", "lvl": "target", "msg": "   Entry Point:  00000000"}
- {"dt": "2024-11-30T09:52:12.906318", "lvl": "target", "msg": "   Verifying Checksum ... OK"}
- {"dt": "2024-11-30T09:52:12.906517", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2024-11-30T09:52:12.906617", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2024-11-30T09:52:12.909423", "lvl": "target", "msg": "   Using Device Tree in place at 0000000068000000, end 0000000068013dee"}
- {"dt": "2024-11-30T09:52:12.924608", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:52:12.924808", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-11-30T09:52:12.924918", "lvl": "target", "msg": ""}
- {"dt": "2024-11-30T09:52:12.925271", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:17) [common]"}
- {"dt": "2024-11-30T09:52:12.925416", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:04:40) [common]"}
- {"dt": "2024-11-30T09:52:12.925517", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-11-30T09:52:12.925608", "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-11-30T09:52:12.925706", "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:05:00)"}
- {"dt": "2024-11-30T09:52:14.516685", "lvl": "target", "msg": "<6>[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2024-11-30T09:52:14.517174", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:04:39) [common]"}
- {"dt": "2024-11-30T09:52:14.517318", "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-11-30T09:52:14.517423", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-11-30T09:52:14.517531", "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-11-30T09:52:14.517622", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-11-30T09:52:14.517699", "lvl": "debug", "msg": "No login prompt set."}
- {"dt": "2024-11-30T09:52:14.517784", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-11-30T09:52:14.517858", "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", "/ #", "Login timed out", "Login incorrect"]}
- {"dt": "2024-11-30T09:52:14.518012", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:04:39)"}
- {"dt": "2024-11-30T09:52:14.518702", "lvl": "target", "msg": "<5>[    0.000000] Linux version 5.3.10 (KernelCI@149ca0145d59) (gcc version 8.3.0 (Debian 8.3.0-2)) #1 SMP PREEMPT Sun Nov 10 13:41:13 UTC 2019"}
- {"dt": "2024-11-30T09:52:14.518820", "lvl": "target", "msg": "<6>[    0.000000] Machine model: Renesas H3ULCB Kingfisher board based on r8a7795 ES2.0+"}
- {"dt": "2024-11-30T09:52:14.518906", "lvl": "target", "msg": "<6>[    0.000000] efi: Getting EFI parameters from FDT:"}
- {"dt": "2024-11-30T09:52:14.518982", "lvl": "target", "msg": "<6>[    0.000000] efi: UEFI not found."}
- {"dt": "2024-11-30T09:52:14.519057", "lvl": "target", "msg": "<6>[    0.000000] cma: Reserved 32 MiB at 0x00000000be000000"}
- {"dt": "2024-11-30T09:52:14.519878", "lvl": "target", "msg": "<6>[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-11-30T09:52:14.559900", "lvl": "target", "msg": "<6>[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-11-30T09:52:14.560112", "lvl": "target", "msg": "<6>[    0.000000] NUMA: NODE_DATA [mem 0x77efdc840-0x77efddfff]"}
- {"dt": "2024-11-30T09:52:14.560215", "lvl": "target", "msg": "<6>[    0.000000] Zone ranges:"}
- {"dt": "2024-11-30T09:52:14.560298", "lvl": "target", "msg": "<6>[    0.000000]   DMA32    [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2024-11-30T09:52:14.560375", "lvl": "target", "msg": "<6>[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2024-11-30T09:52:14.560448", "lvl": "target", "msg": "<6>[    0.000000] Movable zone start for each node"}
- {"dt": "2024-11-30T09:52:14.560521", "lvl": "target", "msg": "<6>[    0.000000] Early memory node ranges"}
- {"dt": "2024-11-30T09:52:14.563039", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000048000000-0x00000000bfffffff]"}
- {"dt": "2024-11-30T09:52:14.603040", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2024-11-30T09:52:14.603236", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2024-11-30T09:52:14.603340", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2024-11-30T09:52:14.603435", "lvl": "target", "msg": "<6>[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-11-30T09:52:14.603523", "lvl": "target", "msg": "<6>[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-11-30T09:52:14.603609", "lvl": "target", "msg": "<6>[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-11-30T09:52:14.605630", "lvl": "target", "msg": "<6>[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-11-30T09:52:14.646163", "lvl": "target", "msg": "<6>[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2024-11-30T09:52:14.646349", "lvl": "target", "msg": "<6>[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-11-30T09:52:14.646441", "lvl": "target", "msg": "<6>[    0.000000] percpu: Embedded 22 pages/cpu s52632 r8192 d29288 u90112"}
- {"dt": "2024-11-30T09:52:14.646527", "lvl": "target", "msg": "<6>[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-11-30T09:52:14.646608", "lvl": "target", "msg": "<6>[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-11-30T09:52:14.646690", "lvl": "target", "msg": "<4>[    0.000000] ARM_SMCCC_ARCH_WORKAROUND_1 missing from firmware"}
- {"dt": "2024-11-30T09:52:14.649284", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2032128"}
- {"dt": "2024-11-30T09:52:14.649412", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2024-11-30T09:52:14.689290", "lvl": "target", "msg": "<5>[    0.000000] Kernel command line: console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp"}
- {"dt": "2024-11-30T09:52:14.689475", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2024-11-30T09:52:14.689565", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-11-30T09:52:14.689646", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2024-11-30T09:52:14.689727", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0xba000000-0xbe000000] (64MB)"}
- {"dt": "2024-11-30T09:52:14.732371", "lvl": "target", "msg": "<6>[    0.000000] Memory: 7960592K/8257536K available (11388K kernel code, 1792K rwdata, 6024K rodata, 4992K init, 447K bss, 264176K reserved, 32768K cma-reserved)"}
- {"dt": "2024-11-30T09:52:14.732740", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2024-11-30T09:52:14.732836", "lvl": "target", "msg": "<6>[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-11-30T09:52:14.732916", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2024-11-30T09:52:14.732994", "lvl": "target", "msg": "<6>[    0.000000] \tTasks RCU enabled."}
- {"dt": "2024-11-30T09:52:14.735449", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-11-30T09:52:14.775598", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2024-11-30T09:52:14.775784", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-11-30T09:52:14.775876", "lvl": "target", "msg": "<4>[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2024-11-30T09:52:14.775956", "lvl": "target", "msg": "<6>[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-11-30T09:52:14.776031", "lvl": "target", "msg": "<5>[    0.000000] random: get_random_bytes called from start_kernel+0x300/0x494 with crng_init=0"}
- {"dt": "2024-11-30T09:52:14.776107", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2024-11-30T09:52:14.818747", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2024-11-30T09:52:14.818949", "lvl": "target", "msg": "<6>[    0.000002] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2024-11-30T09:52:14.819055", "lvl": "target", "msg": "<6>[    0.000108] Console: colour dummy device 80x25"}
- {"dt": "2024-11-30T09:52:14.819136", "lvl": "target", "msg": "<6>[    0.000176] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2024-11-30T09:52:14.819214", "lvl": "target", "msg": "<6>[    0.000185] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-11-30T09:52:14.819287", "lvl": "target", "msg": "<6>[    0.000239] LSM: Security Framework initializing"}
- {"dt": "2024-11-30T09:52:14.861823", "lvl": "target", "msg": "<6>[    0.000315] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-30T09:52:14.862019", "lvl": "target", "msg": "<6>[    0.000361] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-30T09:52:14.862118", "lvl": "target", "msg": "<6>[    0.023976] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-11-30T09:52:14.862202", "lvl": "target", "msg": "<6>[    0.031966] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-11-30T09:52:14.862278", "lvl": "target", "msg": "<6>[    0.040993] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2024-11-30T09:52:14.862352", "lvl": "target", "msg": "<6>[    0.042222] EFI services will not be available."}
- {"dt": "2024-11-30T09:52:14.862426", "lvl": "target", "msg": "<6>[    0.047990] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-11-30T09:52:14.864897", "lvl": "target", "msg": "<6>[    0.080203] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-11-30T09:52:14.904939", "lvl": "target", "msg": "<6>[    0.080239] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2024-11-30T09:52:14.905141", "lvl": "target", "msg": "<6>[    0.112203] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-11-30T09:52:14.905242", "lvl": "target", "msg": "<6>[    0.112224] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2024-11-30T09:52:14.905323", "lvl": "target", "msg": "<6>[    0.144237] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-11-30T09:52:14.905399", "lvl": "target", "msg": "<6>[    0.144256] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2024-11-30T09:52:14.905473", "lvl": "target", "msg": "<6>[    0.176284] CPU features: detected: ARM erratum 845719"}
- {"dt": "2024-11-30T09:52:14.905548", "lvl": "target", "msg": "<6>[    0.176295] Detected VIPT I-cache on CPU4"}
- {"dt": "2024-11-30T09:52:14.907572", "lvl": "target", "msg": "<6>[    0.176333] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2024-11-30T09:52:14.948169", "lvl": "target", "msg": "<6>[    0.208303] Detected VIPT I-cache on CPU5"}
- {"dt": "2024-11-30T09:52:14.948372", "lvl": "target", "msg": "<6>[    0.208326] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2024-11-30T09:52:14.948479", "lvl": "target", "msg": "<6>[    0.240340] Detected VIPT I-cache on CPU6"}
- {"dt": "2024-11-30T09:52:14.948567", "lvl": "target", "msg": "<6>[    0.240364] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2024-11-30T09:52:14.948648", "lvl": "target", "msg": "<6>[    0.272376] Detected VIPT I-cache on CPU7"}
- {"dt": "2024-11-30T09:52:14.948729", "lvl": "target", "msg": "<6>[    0.272399] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2024-11-30T09:52:14.948817", "lvl": "target", "msg": "<6>[    0.272475] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2024-11-30T09:52:14.951201", "lvl": "target", "msg": "<6>[    0.272499] SMP: Total of 8 processors activated."}
- {"dt": "2024-11-30T09:52:14.991267", "lvl": "target", "msg": "<6>[    0.272504] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-11-30T09:52:14.991451", "lvl": "target", "msg": "<6>[    0.272509] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-11-30T09:52:14.991558", "lvl": "target", "msg": "<6>[    0.273357] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-11-30T09:52:14.991640", "lvl": "target", "msg": "<6>[    0.273381] alternatives: patching kernel code"}
- {"dt": "2024-11-30T09:52:14.991716", "lvl": "target", "msg": "<6>[    0.274443] devtmpfs: initialized"}
- {"dt": "2024-11-30T09:52:14.991789", "lvl": "target", "msg": "<6>[    0.279973] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-11-30T09:52:14.994355", "lvl": "target", "msg": "<6>[    0.279988] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.034346", "lvl": "target", "msg": "<6>[    0.280675] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-11-30T09:52:15.034537", "lvl": "target", "msg": "<6>[    0.281814] DMI not present or invalid."}
- {"dt": "2024-11-30T09:52:15.034634", "lvl": "target", "msg": "<6>[    0.282062] NET: Registered protocol family 16"}
- {"dt": "2024-11-30T09:52:15.034721", "lvl": "target", "msg": "<6>[    0.282349] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-11-30T09:52:15.034803", "lvl": "target", "msg": "<5>[    0.282502] audit: type=2000 audit(0.280:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-11-30T09:52:15.034884", "lvl": "target", "msg": "<6>[    0.283443] cpuidle: using governor menu"}
- {"dt": "2024-11-30T09:52:15.034965", "lvl": "target", "msg": "<6>[    0.283589] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-11-30T09:52:15.036588", "lvl": "target", "msg": "<6>[    0.284194] DMA: preallocated 256 KiB pool for atomic allocations"}
- {"dt": "2024-11-30T09:52:15.077586", "lvl": "target", "msg": "<6>[    0.285054] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-11-30T09:52:15.077742", "lvl": "target", "msg": "<6>[    0.287528] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered"}
- {"dt": "2024-11-30T09:52:15.077829", "lvl": "target", "msg": "<6>[    0.305226] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-11-30T09:52:15.077910", "lvl": "target", "msg": "<6>[    0.305234] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-11-30T09:52:15.077988", "lvl": "target", "msg": "<6>[    0.305239] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-11-30T09:52:15.078065", "lvl": "target", "msg": "<6>[    0.305243] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-11-30T09:52:15.080119", "lvl": "target", "msg": "<6>[    0.306585] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-11-30T09:52:15.120595", "lvl": "target", "msg": "<6>[    0.309785] ACPI: Interpreter disabled."}
- {"dt": "2024-11-30T09:52:15.120800", "lvl": "target", "msg": "<6>[    0.313415] vgaarb: loaded"}
- {"dt": "2024-11-30T09:52:15.120902", "lvl": "target", "msg": "<5>[    0.313608] SCSI subsystem initialized"}
- {"dt": "2024-11-30T09:52:15.120988", "lvl": "target", "msg": "<6>[    0.313841] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-11-30T09:52:15.121067", "lvl": "target", "msg": "<6>[    0.313862] usbcore: registered new interface driver hub"}
- {"dt": "2024-11-30T09:52:15.121145", "lvl": "target", "msg": "<6>[    0.313904] usbcore: registered new device driver usb"}
- {"dt": "2024-11-30T09:52:15.121220", "lvl": "target", "msg": "<6>[    0.314738] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2024-11-30T09:52:15.123684", "lvl": "target", "msg": "<6>[    0.314989] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-11-30T09:52:15.163900", "lvl": "target", "msg": "<6>[    0.314993] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-11-30T09:52:15.164069", "lvl": "target", "msg": "<6>[    0.315002] PTP clock support registered"}
- {"dt": "2024-11-30T09:52:15.164152", "lvl": "target", "msg": "<6>[    0.315125] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-11-30T09:52:15.164226", "lvl": "target", "msg": "<6>[    0.316262] FPGA manager framework"}
- {"dt": "2024-11-30T09:52:15.164299", "lvl": "target", "msg": "<6>[    0.316309] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-11-30T09:52:15.164373", "lvl": "target", "msg": "<6>[    0.316769] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-11-30T09:52:15.164447", "lvl": "target", "msg": "<5>[    0.316880] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-11-30T09:52:15.166976", "lvl": "target", "msg": "<6>[    0.316921] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-11-30T09:52:15.206997", "lvl": "target", "msg": "<6>[    0.317037] pnp: PnP ACPI: disabled"}
- {"dt": "2024-11-30T09:52:15.207187", "lvl": "target", "msg": "<6>[    0.319885] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-11-30T09:52:15.207324", "lvl": "target", "msg": "<6>[    0.319887] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-11-30T09:52:15.207481", "lvl": "target", "msg": "<6>[    0.320395] NET: Registered protocol family 2"}
- {"dt": "2024-11-30T09:52:15.207667", "lvl": "target", "msg": "<6>[    0.320616] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.207776", "lvl": "target", "msg": "<6>[    0.320678] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.250114", "lvl": "target", "msg": "<6>[    0.320970] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.250301", "lvl": "target", "msg": "<6>[    0.321552] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-11-30T09:52:15.250384", "lvl": "target", "msg": "<6>[    0.321667] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.250459", "lvl": "target", "msg": "<6>[    0.321774] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-30T09:52:15.250532", "lvl": "target", "msg": "<6>[    0.321986] NET: Registered protocol family 1"}
- {"dt": "2024-11-30T09:52:15.250605", "lvl": "target", "msg": "<6>[    0.322313] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-11-30T09:52:15.250681", "lvl": "target", "msg": "<6>[    0.322318] RPC: Registered udp transport module."}
- {"dt": "2024-11-30T09:52:15.253217", "lvl": "target", "msg": "<6>[    0.322321] RPC: Registered tcp transport module."}
- {"dt": "2024-11-30T09:52:15.293195", "lvl": "target", "msg": "<6>[    0.322324] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-11-30T09:52:15.293608", "lvl": "target", "msg": "<6>[    0.322333] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-11-30T09:52:15.293697", "lvl": "target", "msg": "<6>[    0.322435] Unpacking initramfs..."}
- {"dt": "2024-11-30T09:52:15.293772", "lvl": "target", "msg": "<6>[    0.760963] Freeing initrd memory: 12692K"}
- {"dt": "2024-11-30T09:52:15.293844", "lvl": "target", "msg": "<6>[    0.761790] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2024-11-30T09:52:15.293917", "lvl": "target", "msg": "<6>[    0.762035] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2024-11-30T09:52:15.296256", "lvl": "target", "msg": "<6>[    0.762395] kvm [1]: IPA Size Limit: 40bits"}
- {"dt": "2024-11-30T09:52:15.336386", "lvl": "target", "msg": "<6>[    0.762886] kvm [1]: vgic interrupt IRQ1"}
- {"dt": "2024-11-30T09:52:15.336596", "lvl": "target", "msg": "<6>[    0.763034] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-11-30T09:52:15.336774", "lvl": "target", "msg": "<5>[    0.894371] Initialise system trusted keyrings"}
- {"dt": "2024-11-30T09:52:15.336918", "lvl": "target", "msg": "<6>[    0.894487] workingset: timestamp_bits=44 max_order=21 bucket_order=0"}
- {"dt": "2024-11-30T09:52:15.337086", "lvl": "target", "msg": "<6>[    0.897679] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-11-30T09:52:15.337206", "lvl": "target", "msg": "<5>[    0.898082] NFS: Registering the id_resolver key type"}
- {"dt": "2024-11-30T09:52:15.337280", "lvl": "target", "msg": "<5>[    0.898097] Key type id_resolver registered"}
- {"dt": "2024-11-30T09:52:15.337350", "lvl": "target", "msg": "<5>[    0.898100] Key type id_legacy registered"}
- {"dt": "2024-11-30T09:52:15.379482", "lvl": "target", "msg": "<6>[    0.898109] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-11-30T09:52:15.379683", "lvl": "target", "msg": "<6>[    0.898210] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-11-30T09:52:15.379782", "lvl": "target", "msg": "<5>[    0.913035] Key type asymmetric registered"}
- {"dt": "2024-11-30T09:52:15.379869", "lvl": "target", "msg": "<5>[    0.913043] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-11-30T09:52:15.379949", "lvl": "target", "msg": "<6>[    0.913080] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-11-30T09:52:15.380029", "lvl": "target", "msg": "<6>[    0.913085] io scheduler mq-deadline registered"}
- {"dt": "2024-11-30T09:52:15.380112", "lvl": "target", "msg": "<6>[    0.913089] io scheduler kyber registered"}
- {"dt": "2024-11-30T09:52:15.380189", "lvl": "target", "msg": "<6>[    0.922569] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2024-11-30T09:52:15.422714", "lvl": "target", "msg": "<6>[    0.922753] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2024-11-30T09:52:15.422912", "lvl": "target", "msg": "<6>[    0.922930] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2024-11-30T09:52:15.423008", "lvl": "target", "msg": "<6>[    0.923094] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2024-11-30T09:52:15.423416", "lvl": "target", "msg": "<6>[    0.923250] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2024-11-30T09:52:15.423516", "lvl": "target", "msg": "<6>[    0.923402] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2024-11-30T09:52:15.423593", "lvl": "target", "msg": "<6>[    0.923579] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2024-11-30T09:52:15.423666", "lvl": "target", "msg": "<6>[    0.923728] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2024-11-30T09:52:15.425844", "lvl": "target", "msg": "<6>[    0.925068] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2024-11-30T09:52:15.465843", "lvl": "target", "msg": "<6>[    0.925092] rcar-pcie fe000000.pcie:    IO 0xfe100000..0xfe1fffff -> 0x00000000"}
- {"dt": "2024-11-30T09:52:15.466026", "lvl": "target", "msg": "<6>[    0.925109] rcar-pcie fe000000.pcie:   MEM 0xfe200000..0xfe3fffff -> 0xfe200000"}
- {"dt": "2024-11-30T09:52:15.466118", "lvl": "target", "msg": "<6>[    0.925123] rcar-pcie fe000000.pcie:   MEM 0x30000000..0x37ffffff -> 0x30000000"}
- {"dt": "2024-11-30T09:52:15.466198", "lvl": "target", "msg": "<6>[    0.925132] rcar-pcie fe000000.pcie:   MEM 0x38000000..0x3fffffff -> 0x38000000"}
- {"dt": "2024-11-30T09:52:15.466274", "lvl": "target", "msg": "<6>[    0.992392] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2024-11-30T09:52:15.469219", "lvl": "target", "msg": "<6>[    0.992526] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2024-11-30T09:52:15.508888", "lvl": "target", "msg": "<6>[    0.992544] rcar-pcie ee800000.pcie:    IO 0xee900000..0xee9fffff -> 0x00000000"}
- {"dt": "2024-11-30T09:52:15.509085", "lvl": "target", "msg": "<6>[    0.992558] rcar-pcie ee800000.pcie:   MEM 0xeea00000..0xeebfffff -> 0xeea00000"}
- {"dt": "2024-11-30T09:52:15.509197", "lvl": "target", "msg": "<6>[    0.992570] rcar-pcie ee800000.pcie:   MEM 0xc0000000..0xc7ffffff -> 0xc0000000"}
- {"dt": "2024-11-30T09:52:15.509295", "lvl": "target", "msg": "<6>[    0.992579] rcar-pcie ee800000.pcie:   MEM 0xc8000000..0xcfffffff -> 0xc8000000"}
- {"dt": "2024-11-30T09:52:15.509388", "lvl": "target", "msg": "<6>[    1.056397] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2024-11-30T09:52:15.509477", "lvl": "target", "msg": "<4>[    1.058191] EINJ: ACPI disabled."}
- {"dt": "2024-11-30T09:52:15.511310", "lvl": "target", "msg": "<6>[    1.068439] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-11-30T09:52:15.563037", "lvl": "target", "msg": "<6>[    1.070164] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-11-30T09:52:15.563230", "lvl": "target", "msg": "<6>[    1.070497] e6540000.serial: ttySC1 at MMIO 0xe6540000 (irq = 34, base_baud = 0) is a hscif"}
- {"dt": "2024-11-30T09:52:15.563657", "lvl": "target", "msg": "<6>[    1.070994] e6e68000.serial: ttySC2 at MMIO 0xe6e68000 (irq = 121, base_baud = 0) is a scif"}
- {"dt": "2024-11-30T09:52:15.563775", "lvl": "target", "msg": "<6>[    1.071329] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 122, base_baud = 0) is a scif"}
- {"dt": "2024-11-30T09:52:15.563865", "lvl": "target", "msg": "<6>[    2.138731] printk: console [ttySC0] enabled"}
- {"dt": "2024-11-30T09:52:15.563945", "lvl": "target", "msg": "<6>[    2.143907] msm_serial: driver initialized"}
- {"dt": "2024-11-30T09:52:15.566123", "lvl": "target", "msg": "<6>[    2.154776] loop: module loaded"}
- {"dt": "2024-11-30T09:52:15.607663", "lvl": "target", "msg": "<6>[    2.162509] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2024-11-30T09:52:15.608172", "lvl": "target", "msg": "<6>[    2.167012] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-11-30T09:52:15.608322", "lvl": "target", "msg": "<6>[    2.173038] thunder_xcv, ver 1.0"}
- {"dt": "2024-11-30T09:52:15.608423", "lvl": "target", "msg": "<6>[    2.176539] thunder_bgx, ver 1.0"}
- {"dt": "2024-11-30T09:52:15.608518", "lvl": "target", "msg": "<6>[    2.180046] nicpf, ver 1.0"}
- {"dt": "2024-11-30T09:52:15.608604", "lvl": "target", "msg": "<6>[    2.183567] hclge is initializing"}
- {"dt": "2024-11-30T09:52:15.608686", "lvl": "target", "msg": "<6>[    2.187143] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-11-30T09:52:15.608777", "lvl": "target", "msg": "<6>[    2.194623] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-11-30T09:52:15.610772", "lvl": "target", "msg": "<6>[    2.200223] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k"}
- {"dt": "2024-11-30T09:52:15.653452", "lvl": "target", "msg": "<6>[    2.206314] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-11-30T09:52:15.653656", "lvl": "target", "msg": "<6>[    2.212511] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k"}
- {"dt": "2024-11-30T09:52:15.653763", "lvl": "target", "msg": "<6>[    2.219731] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-11-30T09:52:15.653852", "lvl": "target", "msg": "<6>[    2.225579] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k"}
- {"dt": "2024-11-30T09:52:15.653939", "lvl": "target", "msg": "<6>[    2.233667] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-11-30T09:52:15.654020", "lvl": "target", "msg": "<6>[    2.240150] sky2: driver version 1.30"}
- {"dt": "2024-11-30T09:52:15.655529", "lvl": "target", "msg": "<6>[    2.244996] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-11-30T09:52:15.679999", "lvl": "target", "msg": "<6>[    2.251750] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-11-30T09:52:15.680198", "lvl": "target", "msg": "<6>[    2.258565] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-11-30T09:52:15.681969", "lvl": "target", "msg": "<6>[    2.263290] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-11-30T09:52:15.727941", "lvl": "target", "msg": "<6>[    2.296828] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2024-11-30T09:52:15.728147", "lvl": "target", "msg": "<6>[    2.302761] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-11-30T09:52:15.730095", "lvl": "target", "msg": "<6>[    2.310925] ehci-platform ee080100.usb: irq 158, io mem 0xee080100"}
- {"dt": "2024-11-30T09:52:15.780913", "lvl": "target", "msg": "<6>[    2.332776] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-11-30T09:52:15.781106", "lvl": "target", "msg": "<6>[    2.339536] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-11-30T09:52:15.781214", "lvl": "target", "msg": "<6>[    2.343562] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-11-30T09:52:15.781308", "lvl": "target", "msg": "<6>[    2.348007] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2024-11-30T09:52:15.781394", "lvl": "target", "msg": "<6>[    2.353939] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-11-30T09:52:15.782970", "lvl": "target", "msg": "<6>[    2.362078] ehci-platform ee0a0100.usb: irq 159, io mem 0xee0a0100"}
- {"dt": "2024-11-30T09:52:15.825057", "lvl": "target", "msg": "<6>[    2.380777] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-11-30T09:52:15.825249", "lvl": "target", "msg": "<6>[    2.387450] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-11-30T09:52:15.825344", "lvl": "target", "msg": "<6>[    2.391478] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-11-30T09:52:15.825427", "lvl": "target", "msg": "<6>[    2.395870] ehci-orion: EHCI orion driver"}
- {"dt": "2024-11-30T09:52:15.825506", "lvl": "target", "msg": "<6>[    2.400293] ehci-exynos: EHCI EXYNOS driver"}
- {"dt": "2024-11-30T09:52:15.825585", "lvl": "target", "msg": "<6>[    2.404832] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-11-30T09:52:15.825663", "lvl": "target", "msg": "<6>[    2.411285] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-11-30T09:52:15.825740", "lvl": "target", "msg": "<6>[    2.416015] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-11-30T09:52:15.856830", "lvl": "target", "msg": "<6>[    2.421644] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-11-30T09:52:15.857033", "lvl": "target", "msg": "<6>[    2.428614] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-11-30T09:52:15.859956", "lvl": "target", "msg": "<6>[    2.436740] ohci-platform ee080000.usb: irq 158, io mem 0xee080000"}
- {"dt": "2024-11-30T09:52:15.973785", "lvl": "target", "msg": "<6>[    2.531737] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-11-30T09:52:15.973982", "lvl": "target", "msg": "<6>[    2.535762] hub 3-0:1.0: 1 port detected"}
- {"dt": "2024-11-30T09:52:15.974080", "lvl": "target", "msg": "<6>[    2.540155] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-11-30T09:52:15.974169", "lvl": "target", "msg": "<6>[    2.547125] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-11-30T09:52:15.975927", "lvl": "target", "msg": "<6>[    2.555251] ohci-platform ee0a0000.usb: irq 159, io mem 0xee0a0000"}
- {"dt": "2024-11-30T09:52:16.097665", "lvl": "target", "msg": "<6>[    2.651715] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-11-30T09:52:16.097866", "lvl": "target", "msg": "<6>[    2.655739] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-11-30T09:52:16.097970", "lvl": "target", "msg": "<6>[    2.660133] ohci-exynos: OHCI EXYNOS driver"}
- {"dt": "2024-11-30T09:52:16.098070", "lvl": "target", "msg": "<6>[    2.664965] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-11-30T09:52:16.098157", "lvl": "target", "msg": "<6>[    2.670460] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2024-11-30T09:52:16.098242", "lvl": "target", "msg": "<4>[    2.678168] xhci-hcd ee000000.usb: Direct firmware load for r8a779x_usb3_v3.dlmem failed with error -2"}
- {"dt": "2024-11-30T09:52:16.098326", "lvl": "target", "msg": "<3>[    2.687753] xhci-hcd ee000000.usb: can't setup: -2"}
- {"dt": "2024-11-30T09:52:16.099853", "lvl": "target", "msg": "<6>[    2.692808] xhci-hcd ee000000.usb: USB bus 5 deregistered"}
- {"dt": "2024-11-30T09:52:16.154138", "lvl": "target", "msg": "<4>[    2.698496] xhci-hcd: probe of ee000000.usb failed with error -2"}
- {"dt": "2024-11-30T09:52:16.154324", "lvl": "target", "msg": "<6>[    2.705043] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-11-30T09:52:16.154425", "lvl": "target", "msg": "<6>[    2.713683] i2c /dev entries driver"}
- {"dt": "2024-11-30T09:52:16.154508", "lvl": "target", "msg": "<6>[    2.725226] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2024-11-30T09:52:16.154587", "lvl": "target", "msg": "<6>[    2.728778] usb 2-1: new high-speed USB device number 2 using ehci-platform"}
- {"dt": "2024-11-30T09:52:16.154663", "lvl": "target", "msg": "<4>[    2.729857] pca953x 2-0074: 2-0074 supply vcc not found, using dummy regulator"}
- {"dt": "2024-11-30T09:52:16.157192", "lvl": "target", "msg": "<6>[    2.746507] GPIO line 340 (Audio_Out_OFF) hogged as output/high"}
- {"dt": "2024-11-30T09:52:16.204779", "lvl": "target", "msg": "<6>[    2.753375] GPIO line 345 (SD WiFi mux) hogged as output/low"}
- {"dt": "2024-11-30T09:52:16.204973", "lvl": "target", "msg": "<6>[    2.759978] GPIO line 346 (HUB pwen) hogged as output/high"}
- {"dt": "2024-11-30T09:52:16.205071", "lvl": "target", "msg": "<6>[    2.766417] GPIO line 347 (HUB rst) hogged as output/high"}
- {"dt": "2024-11-30T09:52:16.205164", "lvl": "target", "msg": "<6>[    2.773660] GPIO line 348 (OTG OFFVBUSn) hogged as output/low"}
- {"dt": "2024-11-30T09:52:16.205256", "lvl": "target", "msg": "<6>[    2.780351] GPIO line 349 (OTG EXTLPn) hogged as output/high"}
- {"dt": "2024-11-30T09:52:16.205337", "lvl": "target", "msg": "<6>[    2.786955] GPIO line 355 (SND_RST) hogged as output/high"}
- {"dt": "2024-11-30T09:52:16.207618", "lvl": "target", "msg": "<4>[    2.793495] pca953x 2-0075: 2-0075 supply vcc not found, using dummy regulator"}
- {"dt": "2024-11-30T09:52:16.251962", "lvl": "target", "msg": "<6>[    2.803240] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2024-11-30T09:52:16.252153", "lvl": "target", "msg": "<4>[    2.807946] pca953x 4-0076: 4-0076 supply vcc not found, using dummy regulator"}
- {"dt": "2024-11-30T09:52:16.252252", "lvl": "target", "msg": "<4>[    2.816467] pca953x 4-0077: 4-0077 supply vcc not found, using dummy regulator"}
- {"dt": "2024-11-30T09:52:16.252333", "lvl": "target", "msg": "<6>[    2.825001] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2024-11-30T09:52:16.252412", "lvl": "target", "msg": "<6>[    2.830380] i2c i2c-2: Added multiplexed i2c bus 8"}
- {"dt": "2024-11-30T09:52:16.252490", "lvl": "target", "msg": "<6>[    2.835526] i2c i2c-2: Added multiplexed i2c bus 9"}
- {"dt": "2024-11-30T09:52:16.252572", "lvl": "target", "msg": "<6>[    2.840668] i2c i2c-2: Added multiplexed i2c bus 10"}
- {"dt": "2024-11-30T09:52:16.254072", "lvl": "target", "msg": "<6>[    2.845917] i2c i2c-2: Added multiplexed i2c bus 11"}
- {"dt": "2024-11-30T09:52:16.296152", "lvl": "target", "msg": "<6>[    2.851145] i2c i2c-2: Added multiplexed i2c bus 12"}
- {"dt": "2024-11-30T09:52:16.296332", "lvl": "target", "msg": "<6>[    2.856379] i2c i2c-2: Added multiplexed i2c bus 13"}
- {"dt": "2024-11-30T09:52:16.296434", "lvl": "target", "msg": "<6>[    2.861613] i2c i2c-2: Added multiplexed i2c bus 14"}
- {"dt": "2024-11-30T09:52:16.296524", "lvl": "target", "msg": "<6>[    2.866923] i2c i2c-2: Added multiplexed i2c bus 15"}
- {"dt": "2024-11-30T09:52:16.296609", "lvl": "target", "msg": "<6>[    2.872068] pca954x 2-0071: registered 8 multiplexed busses for I2C switch pca9548"}
- {"dt": "2024-11-30T09:52:16.296694", "lvl": "target", "msg": "<6>[    2.880205] i2c i2c-4: Added multiplexed i2c bus 16"}
- {"dt": "2024-11-30T09:52:16.296791", "lvl": "target", "msg": "<6>[    2.885434] i2c i2c-4: Added multiplexed i2c bus 17"}
- {"dt": "2024-11-30T09:52:16.299155", "lvl": "target", "msg": "<6>[    2.890662] i2c i2c-4: Added multiplexed i2c bus 18"}
- {"dt": "2024-11-30T09:52:16.349892", "lvl": "target", "msg": "<6>[    2.895891] i2c i2c-4: Added multiplexed i2c bus 19"}
- {"dt": "2024-11-30T09:52:16.350094", "lvl": "target", "msg": "<6>[    2.901122] i2c i2c-4: Added multiplexed i2c bus 20"}
- {"dt": "2024-11-30T09:52:16.350203", "lvl": "target", "msg": "<6>[    2.906358] i2c i2c-4: Added multiplexed i2c bus 21"}
- {"dt": "2024-11-30T09:52:16.350615", "lvl": "target", "msg": "<6>[    2.911592] i2c i2c-4: Added multiplexed i2c bus 22"}
- {"dt": "2024-11-30T09:52:16.350716", "lvl": "target", "msg": "<6>[    2.916826] i2c i2c-4: Added multiplexed i2c bus 23"}
- {"dt": "2024-11-30T09:52:16.350800", "lvl": "target", "msg": "<6>[    2.921972] pca954x 4-0071: registered 8 multiplexed busses for I2C switch pca9548"}
- {"dt": "2024-11-30T09:52:16.352656", "lvl": "target", "msg": "<6>[    2.935848] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2024-11-30T09:52:16.400088", "lvl": "target", "msg": "<6>[    2.947123] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2024-11-30T09:52:16.400612", "lvl": "target", "msg": "<6>[    2.958392] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 2 trip points"}
- {"dt": "2024-11-30T09:52:16.400761", "lvl": "target", "msg": "<4>[    2.968047] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 1499999 KHz"}
- {"dt": "2024-11-30T09:52:16.400867", "lvl": "target", "msg": "<4>[    2.975740] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 1500000 KHz"}
- {"dt": "2024-11-30T09:52:16.400962", "lvl": "target", "msg": "<4>[    2.984869] cpufreq: cpufreq_online: CPU4: Running at unlisted freq: 1199999 KHz"}
- {"dt": "2024-11-30T09:52:16.433516", "lvl": "target", "msg": "<4>[    2.992531] cpufreq: cpufreq_online: CPU4: Unlisted initial frequency changed to: 1200000 KHz"}
- {"dt": "2024-11-30T09:52:16.433715", "lvl": "target", "msg": "<6>[    3.002154] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-11-30T09:52:16.433821", "lvl": "target", "msg": "<6>[    3.008604] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-11-30T09:52:16.435718", "lvl": "target", "msg": "<6>[    3.013811] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO"}
- {"dt": "2024-11-30T09:52:16.517732", "lvl": "target", "msg": "<6>[    3.068942] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz"}
- {"dt": "2024-11-30T09:52:16.517921", "lvl": "target", "msg": "<6>[    3.078784] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-11-30T09:52:16.518028", "lvl": "target", "msg": "<6>[    3.085941] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-11-30T09:52:16.518120", "lvl": "target", "msg": "<6>[    3.093489] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-11-30T09:52:16.518207", "lvl": "target", "msg": "<6>[    3.100803] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-11-30T09:52:16.518292", "lvl": "target", "msg": "<5>[    3.105983] random: fast init done"}
- {"dt": "2024-11-30T09:52:16.520812", "lvl": "target", "msg": "<6>[    3.106634] usbhid: USB HID core driver"}
- {"dt": "2024-11-30T09:52:16.575720", "lvl": "target", "msg": "<6>[    3.116590] optee: probing for conduit method from DT."}
- {"dt": "2024-11-30T09:52:16.575914", "lvl": "target", "msg": "<6>[    3.122017] optee: revision 3.22 (314ecf9d)"}
- {"dt": "2024-11-30T09:52:16.576020", "lvl": "target", "msg": "<6>[    3.122210] optee: dynamic shared memory is enabled"}
- {"dt": "2024-11-30T09:52:16.576122", "lvl": "target", "msg": "<3>[    3.131928] optee: PTA_CMD_GET_DEVICES invoke function err: ffff0006"}
- {"dt": "2024-11-30T09:52:16.576205", "lvl": "target", "msg": "<6>[    3.145699] NET: Registered protocol family 17"}
- {"dt": "2024-11-30T09:52:16.576284", "lvl": "target", "msg": "<6>[    3.150528] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-11-30T09:52:16.576360", "lvl": "target", "msg": "<5>[    3.155102] Key type dns_resolver registered"}
- {"dt": "2024-11-30T09:52:16.576437", "lvl": "target", "msg": "<6>[    3.159952] registered taskstats version 1"}
- {"dt": "2024-11-30T09:52:16.578669", "lvl": "target", "msg": "<5>[    3.164313] Loading compiled-in X.509 certificates"}
- {"dt": "2024-11-30T09:52:16.630994", "lvl": "target", "msg": "<6>[    3.176195] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2024-11-30T09:52:16.631196", "lvl": "target", "msg": "<6>[    3.190227] bd9571mwv 7-0030: Device: BD9571MWV rev. 4"}
- {"dt": "2024-11-30T09:52:16.631289", "lvl": "target", "msg": "<6>[    3.193625] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2024-11-30T09:52:16.631375", "lvl": "target", "msg": "<6>[    3.201376] mmcblk0: mmc0:0001 DJUD4R 119 GiB "}
- {"dt": "2024-11-30T09:52:16.631461", "lvl": "target", "msg": "<6>[    3.206317] mmcblk0boot0: mmc0:0001 DJUD4R partition 1 31.9 MiB"}
- {"dt": "2024-11-30T09:52:16.631545", "lvl": "target", "msg": "<6>[    3.212683] mmcblk0boot1: mmc0:0001 DJUD4R partition 2 31.9 MiB"}
- {"dt": "2024-11-30T09:52:16.634126", "lvl": "target", "msg": "<6>[    3.218977] mmcblk0rpmb: mmc0:0001 DJUD4R partition 3 4.00 MiB, chardev (237:0)"}
- {"dt": "2024-11-30T09:52:16.649455", "lvl": "target", "msg": "<6>[    3.230298] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO"}
- {"dt": "2024-11-30T09:52:16.707534", "lvl": "target", "msg": "<6>[    3.284995] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz"}
- {"dt": "2024-11-30T09:52:16.873065", "lvl": "target", "msg": "<6>[    3.422876] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 26 MHz"}
- {"dt": "2024-11-30T09:52:16.873273", "lvl": "target", "msg": "<4>[    3.435165] rcar-dmac e6700000.dma-controller: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.873377", "lvl": "target", "msg": "<4>[    3.446271] rcar-dmac e7300000.dma-controller: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.873469", "lvl": "target", "msg": "<4>[    3.457412] rcar-dmac e7310000.dma-controller: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.925013", "lvl": "target", "msg": "<4>[    3.467211] renesas_sdhi_internal_dmac ee160000.sd: card claims to support voltages below defined range"}
- {"dt": "2024-11-30T09:52:16.925207", "lvl": "target", "msg": "<4>[    3.468488] rcar-dmac ec700000.dma-controller: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.925304", "lvl": "target", "msg": "<4>[    3.487996] rcar-dmac ec720000.dma-controller: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.925388", "lvl": "target", "msg": "<6>[    3.490307] mmc2: new high speed SDIO card at address 0001"}
- {"dt": "2024-11-30T09:52:16.925469", "lvl": "target", "msg": "<4>[    3.499636] ravb e6800000.ethernet: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:16.927585", "lvl": "target", "msg": "<6>[    3.511384] libphy: ravb_mii: probed"}
- {"dt": "2024-11-30T09:52:16.958269", "lvl": "target", "msg": "<6>[    3.521690] ravb e6800000.ethernet eth0: Base address at 0xe6800000, aa:bb:cc:dd:ee:77, IRQ 116."}
- {"dt": "2024-11-30T09:52:16.958474", "lvl": "target", "msg": "<6>[    3.531962] input: keyboard as /devices/platform/keyboard/input/input0"}
- {"dt": "2024-11-30T09:52:16.961403", "lvl": "target", "msg": "<6>[    3.538988] hctosys: unable to open rtc device (rtc0)"}
- {"dt": "2024-11-30T09:52:17.051783", "lvl": "target", "msg": "<6>[    3.619883] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=e6800000.ethernet-ffffffff:00, irq=189)"}
- {"dt": "2024-11-30T09:52:19.310579", "lvl": "target", "msg": "<6>[    5.886207] ravb e6800000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off"}
- {"dt": "2024-11-30T09:52:21.999183", "lvl": "target", "msg": "<5>[    5.908774] Sending DHCP requests .., OK"}
- {"dt": "2024-11-30T09:52:21.999717", "lvl": "target", "msg": "<6>[    8.560982] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.15"}
- {"dt": "2024-11-30T09:52:21.999840", "lvl": "target", "msg": "<6>[    8.569086] IP-Config: Complete:"}
- {"dt": "2024-11-30T09:52:21.999933", "lvl": "target", "msg": "<6>[    8.572572]      device=eth0, hwaddr=aa:bb:cc:dd:ee:77, ipaddr=192.168.66.15, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-11-30T09:52:22.000019", "lvl": "target", "msg": "<6>[    8.583095]      host=192.168.66.15, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-11-30T09:52:22.002342", "lvl": "target", "msg": "<6>[    8.590229]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2024-11-30T09:52:22.039564", "lvl": "target", "msg": "<6>[    8.590231]      nameserver0=10.1.1.1"}
- {"dt": "2024-11-30T09:52:22.039759", "lvl": "target", "msg": "<6>[    8.600973] ALSA device list:"}
- {"dt": "2024-11-30T09:52:22.039848", "lvl": "target", "msg": "<6>[    8.604199]   No soundcards found."}
- {"dt": "2024-11-30T09:52:22.042376", "lvl": "target", "msg": "<6>[    8.610624] Freeing unused kernel memory: 4992K"}
- {"dt": "2024-11-30T09:52:22.042511", "lvl": "target", "msg": "<6>[    8.620834] Run /init as init process"}
- {"dt": "2024-11-30T09:52:22.105547", "lvl": "target", "msg": "Starting syslogd: OK"}
- {"dt": "2024-11-30T09:52:22.105747", "lvl": "target", "msg": "Starting klogd: OK"}
- {"dt": "2024-11-30T09:52:22.105838", "lvl": "target", "msg": "Populating /dev using udev: <30>[    8.654769] udevd[303]: starting version 3.2.7"}
- {"dt": "2024-11-30T09:52:22.105918", "lvl": "target", "msg": "<5>[    8.660030] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-11-30T09:52:22.105996", "lvl": "target", "msg": "<5>[    8.666848] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-11-30T09:52:22.106069", "lvl": "target", "msg": "<5>[    8.673621] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-11-30T09:52:22.106470", "lvl": "target", "msg": "<27>[    8.681426] udevd[303]: specified group 'kvm' unknown"}
- {"dt": "2024-11-30T09:52:22.108687", "lvl": "target", "msg": "<30>[    8.689564] udevd[304]: starting eudev-3.2.7"}
- {"dt": "2024-11-30T09:52:22.219446", "lvl": "target", "msg": "<6>[    8.793721] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2024-11-30T09:52:22.222475", "lvl": "target", "msg": "<6>[    8.799932] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2024-11-30T09:52:22.222611", "lvl": "target", "msg": "<6>[    8.810551] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2024-11-30T09:52:22.238439", "lvl": "target", "msg": "<6>[    8.826520] rcar_sound ec500000.sound: probed"}
- {"dt": "2024-11-30T09:52:22.286212", "lvl": "target", "msg": "<6>[    8.839640] mc: Linux media interface: v0.10"}
- {"dt": "2024-11-30T09:52:22.286402", "lvl": "target", "msg": "<4>[    8.844760] rcar-fcp fe950000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.286495", "lvl": "target", "msg": "<6>[    8.853544] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-11-30T09:52:22.286900", "lvl": "target", "msg": "<4>[    8.854488] rcar-fcp fe951000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.287001", "lvl": "target", "msg": "<4>[    8.867728] rcar-fcp fe96f000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.289320", "lvl": "target", "msg": "<4>[    8.876649] rcar-fcp fe92f000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.344327", "lvl": "target", "msg": "<4>[    8.886477] rcar-fcp fe9af000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.344527", "lvl": "target", "msg": "<4>[    8.896072] rcar-fcp fe9bf000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.344617", "lvl": "target", "msg": "<4>[    8.905225] rcar-fcp fea27000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.344699", "lvl": "target", "msg": "<4>[    8.914124] rcar-fcp fea2f000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.347454", "lvl": "target", "msg": "<4>[    8.923574] rcar-fcp fea37000.fcp: ignoring dependency for device, assuming no driver"}
- {"dt": "2024-11-30T09:52:22.420246", "lvl": "target", "msg": "<6>[    8.994353] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-11-30T09:52:22.423344", "lvl": "target", "msg": "<6>[    9.001319] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-11-30T09:52:22.534208", "lvl": "target", "msg": "<6>[    9.096635] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-11-30T09:52:22.534393", "lvl": "target", "msg": "<6>[    9.103542] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-11-30T09:52:22.534491", "lvl": "target", "msg": "<5>[    9.111666] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-11-30T09:52:22.567264", "lvl": "target", "msg": "<5>[    9.136405] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-11-30T09:52:22.567499", "lvl": "target", "msg": "<4>[    9.143287] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-11-30T09:52:22.569976", "lvl": "target", "msg": "<6>[    9.152177] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-11-30T09:52:22.739608", "lvl": "target", "msg": "<4>[    9.293499] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2"}
- {"dt": "2024-11-30T09:52:22.739820", "lvl": "target", "msg": "done"}
- {"dt": "2024-11-30T09:52:22.739920", "lvl": "target", "msg": "<4>[    9.304426] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl18xx-conf.bin failed with error -2"}
- {"dt": "2024-11-30T09:52:22.740008", "lvl": "target", "msg": "Initializing ran<3>[    9.315880] wlcore: ERROR could not get configuration binary ti-connectivity/wl18xx-conf.bin: -2"}
- {"dt": "2024-11-30T09:52:22.740090", "lvl": "target", "msg": "dom number gener<4>[    9.326310] wlcore: WARNING falling back to default config"}
- {"dt": "2024-11-30T09:52:22.740171", "lvl": "target", "msg": "ator... done."}
- {"dt": "2024-11-30T09:52:22.779987", "lvl": "target", "msg": "<6>[    9.336074] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013)."}
- {"dt": "2024-11-30T09:52:22.780183", "lvl": "target", "msg": "<6>[    9.342967] [drm] No driver support for vblank timestamp query."}
- {"dt": "2024-11-30T09:52:22.783126", "lvl": "target", "msg": "Starting network: ip: RTNETLINK answers: File exists"}
- {"dt": "2024-11-30T09:52:22.783269", "lvl": "target", "msg": "FAIL"}
- {"dt": "2024-11-30T09:52:22.817143", "lvl": "target", "msg": "Starting dropbear sshd: <6>[    9.384126] NET: Registered protocol family 10"}
- {"dt": "2024-11-30T09:52:22.817309", "lvl": "target", "msg": "<6>[    9.389654] Segment Routing with IPv6"}
- {"dt": "2024-11-30T09:52:22.817403", "lvl": "target", "msg": "OK"}
- {"dt": "2024-11-30T09:52:22.820169", "lvl": "target", "msg": "/bin/sh: can't access tty; job control turned off"}
- {"dt": "2024-11-30T09:52:22.820596", "lvl": "debug", "msg": "Matched prompt #10: / #"}
- {"dt": "2024-11-30T09:52:22.820708", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "8.30", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:52:22.820883", "lvl": "debug", "msg": "Setting prompt string to ['/ #']"}
- {"dt": "2024-11-30T09:52:22.821014", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:08) [common]"}
- {"dt": "2024-11-30T09:52:22.821110", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "8.30", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:52:22.821291", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:10) [common]"}
- {"dt": "2024-11-30T09:52:22.821407", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:30) [common]"}
- {"dt": "2024-11-30T09:52:22.821525", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:30) [common]"}
- {"dt": "2024-11-30T09:52:22.821641", "lvl": "info", "msg": "start: 3 finalize (timeout 00:09:23) [common]"}
- {"dt": "2024-11-30T09:52:22.821754", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-11-30T09:52:22.821926", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2024-11-30T09:52:22.937577", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-11-30T09:52:22.945642", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-11-30T09:52:23.046170", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-11-30T09:52:23.046395", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "0.22", "extra": {"status": "success"}, "level": "3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-30T09:52:23.046628", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:09:23) [common]"}
- {"dt": "2024-11-30T09:52:24.047868", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-11-30T09:52:24.048102", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-11-30T09:52:24.048222", "lvl": "target", "msg": "/ # \0"}
- {"dt": "2024-11-30T09:52:24.148593", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:52:24.148843", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-11-30T09:52:24.149011", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-11-30T09:52:24.149166", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/ramdisk"}
- {"dt": "2024-11-30T09:52:24.150721", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/kernel"}
- {"dt": "2024-11-30T09:52:24.153740", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/dtb"}
- {"dt": "2024-11-30T09:52:24.154039", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38024/tftp-deploy-by6h712z/modules"}
- {"dt": "2024-11-30T09:52:24.157514", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38024"}
- {"dt": "2024-11-30T09:52:24.195581", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38024"}
- {"dt": "2024-11-30T09:52:24.195826", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-11-30T09:52:24.195936", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
