- {"dt": "2024-07-11T08:36:08.203995", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-07-11T08:36:08.206279", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-07-11T08:36:08.207660", "lvl": "info", "msg": "Start time: 2024-07-11 08:36:08.207605+00:00 (UTC)"}
- {"dt": "2024-07-11T08:36:08.209214", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-11-g17e793fa4728/arm64/base/rootfs.cpio.gz?raw=true exists"}
- {"dt": "2024-07-11T08:36:10.023385", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/Image?raw=true exists"}
- {"dt": "2024-07-11T08:36:12.634749", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/dtbs/broadcom/bcm2711-rpi-4-b.dtb?raw=true exists"}
- {"dt": "2024-07-11T08:36:13.290204", "lvl": "debug", "msg": "Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/modules.tar.xz?raw=true exists"}
- {"dt": "2024-07-11T08:36:14.434581", "lvl": "info", "msg": "validate duration: 6.23"}
- {"dt": "2024-07-11T08:36:14.435696", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-07-11T08:36:14.437177", "lvl": "info", "msg": "start: 1 tftp-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T08:36:14.438326", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T08:36:14.439409", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T08:36:14.440714", "lvl": "debug", "msg": "Not decompressing ramdisk as can be used compressed."}
- {"dt": "2024-07-11T08:36:14.441755", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-11-g17e793fa4728/arm64/base/rootfs.cpio.gz?raw=true"}
- {"dt": "2024-07-11T08:36:14.442532", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/ramdisk/rootfs.cpio.gz"}
- {"dt": "2024-07-11T08:36:14.443265", "lvl": "debug", "msg": "total size: 4192947 (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.005363", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.043719", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.069798", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.097147", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.120186", "lvl": "debug", "msg": "progress  20\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.141810", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.165009", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.185325", "lvl": "debug", "msg": "progress  35\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.206970", "lvl": "debug", "msg": "progress  40\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.223053", "lvl": "debug", "msg": "progress  45\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.238297", "lvl": "debug", "msg": "progress  50\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.258062", "lvl": "debug", "msg": "progress  55\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.272376", "lvl": "debug", "msg": "progress  60\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.289188", "lvl": "debug", "msg": "progress  65\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.303233", "lvl": "debug", "msg": "progress  70\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.317717", "lvl": "debug", "msg": "progress  75\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.331512", "lvl": "debug", "msg": "progress  80\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.344718", "lvl": "debug", "msg": "progress  85\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.359566", "lvl": "debug", "msg": "progress  90\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.370020", "lvl": "debug", "msg": "progress  95\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.382095", "lvl": "debug", "msg": "progress 100\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.384011", "lvl": "info", "msg": "3\u202FMB downloaded in 0.94\u202Fs (4.25\u202FMB/s)"}
- {"dt": "2024-07-11T08:36:15.385139", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T08:36:15.385756", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.95", "extra": {"label": "ramdisk", "md5sum": "dc99d8359b36a4a5fd569a984bb84bdf", "sha256sum": "cc73f153f47870c8ec995a00a0ad2aec3f05b6d22fc90b37a61f5066faa1b684", "sha512sum": "8fc07bd282f59a659b6417e8faca6c464be64927ab091cb2a9908805d12a680377bb58c5e52a1ed4d877b6f68783c02335e94ecf2764f5198816d2afd293923a", "size": ! "4192947"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:15.387078", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T08:36:15.387725", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-07-11T08:36:15.388388", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-07-11T08:36:15.389400", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/Image?raw=true"}
- {"dt": "2024-07-11T08:36:15.389993", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/kernel/Image"}
- {"dt": "2024-07-11T08:36:15.390451", "lvl": "debug", "msg": "total size: 27247104 (25\u202FMB)"}
- {"dt": "2024-07-11T08:36:15.390911", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-07-11T08:36:17.918960", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:17.987169", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.052724", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.117977", "lvl": "debug", "msg": "progress  15\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.185014", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.248286", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.312265", "lvl": "debug", "msg": "progress  30\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.378006", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.439838", "lvl": "debug", "msg": "progress  40\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.505869", "lvl": "debug", "msg": "progress  45\u202F% (11\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.569381", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.634291", "lvl": "debug", "msg": "progress  55\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.697253", "lvl": "debug", "msg": "progress  60\u202F% (15\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.761712", "lvl": "debug", "msg": "progress  65\u202F% (16\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.825645", "lvl": "debug", "msg": "progress  70\u202F% (18\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.888386", "lvl": "debug", "msg": "progress  75\u202F% (19\u202FMB)"}
- {"dt": "2024-07-11T08:36:18.952434", "lvl": "debug", "msg": "progress  80\u202F% (20\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.015811", "lvl": "debug", "msg": "progress  85\u202F% (22\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.080585", "lvl": "debug", "msg": "progress  90\u202F% (23\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.143176", "lvl": "debug", "msg": "progress  95\u202F% (24\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.200614", "lvl": "debug", "msg": "progress 100\u202F% (25\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.202074", "lvl": "info", "msg": "25\u202FMB downloaded in 3.81\u202Fs (6.82\u202FMB/s)"}
- {"dt": "2024-07-11T08:36:19.203104", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:04) [common]"}
- {"dt": "2024-07-11T08:36:19.203663", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.81", "extra": {"label": "kernel", "md5sum": "72d29fea218746b728a438f8a5b06fcd", "sha256sum": "038284bca9e8e0d7a7f18c35684550365ede71fa430f977f893544ddbbc358c3", "sha512sum": "1677e3adf0de7906c97fbce4dcdde51fcf68b4fd7131c17fdbbd7e9d573686d2adb5fe65a35184ecbffd2bde054d852ec80dafe5699e26491c46bcf588e8208e", "size": ! "27247104"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:19.205020", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:04) [common]"}
- {"dt": "2024-07-11T08:36:19.205680", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:55) [common]"}
- {"dt": "2024-07-11T08:36:19.206305", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:55) [common]"}
- {"dt": "2024-07-11T08:36:19.207247", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/dtbs/broadcom/bcm2711-rpi-4-b.dtb?raw=true"}
- {"dt": "2024-07-11T08:36:19.207828", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb"}
- {"dt": "2024-07-11T08:36:19.208283", "lvl": "debug", "msg": "total size: 22210 (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.208809", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-07-11T08:36:19.424491", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.426798", "lvl": "info", "msg": "0\u202FMB downloaded in 0.22\u202Fs (0.10\u202FMB/s)"}
- {"dt": "2024-07-11T08:36:19.428358", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:19.429435", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.22", "extra": {"label": "dtb", "md5sum": "35e07c8c1178357e2ca07c7c96d27eb2", "sha256sum": "afaf477d22603c52e823a22f64edf96f8a5ff2899f37159fe03a087a8cef7afd", "sha512sum": "f8b5cd80677a91eabd047d7d044b1e1880560f578b31b1beec8d700e455804d74ed10ece7f61b8870189da08c83d121b0f140ae6b7d1752cbdaba48b0dea3cab", "size": ! "22210"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:19.432145", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:19.433447", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:55) [common]"}
- {"dt": "2024-07-11T08:36:19.434687", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:55) [common]"}
- {"dt": "2024-07-11T08:36:19.436426", "lvl": "info", "msg": "downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/modules.tar.xz?raw=true"}
- {"dt": "2024-07-11T08:36:19.437019", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/modules/modules.tar"}
- {"dt": "2024-07-11T08:36:19.437479", "lvl": "debug", "msg": "total size: 6392588 (6\u202FMB)"}
- {"dt": "2024-07-11T08:36:19.437946", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T08:36:19.991253", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.044931", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.109381", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.173590", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.252446", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.321499", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.393741", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.467089", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.537547", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.598873", "lvl": "debug", "msg": "progress  45\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.668908", "lvl": "debug", "msg": "progress  50\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.746005", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.820395", "lvl": "debug", "msg": "progress  60\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.885440", "lvl": "debug", "msg": "progress  65\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T08:36:20.954330", "lvl": "debug", "msg": "progress  70\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.024501", "lvl": "debug", "msg": "progress  75\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.091308", "lvl": "debug", "msg": "progress  80\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.149764", "lvl": "debug", "msg": "progress  85\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.223472", "lvl": "debug", "msg": "progress  90\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.292388", "lvl": "debug", "msg": "progress  95\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.357344", "lvl": "debug", "msg": "progress 100\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T08:36:21.376292", "lvl": "info", "msg": "6\u202FMB downloaded in 1.94\u202Fs (3.14\u202FMB/s)"}
- {"dt": "2024-07-11T08:36:21.377742", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-07-11T08:36:21.378475", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.94", "extra": {"label": "modules", "md5sum": "0724e49cc73f2f495ae13aa5497b2bec", "sha256sum": "1dd2900e982fee1c2772266b84169ee4ebbb2f5410d241faff08c4642b1616a8", "sha512sum": "897029f872c5b8d5e70944a473d61ea0441f43dd2eaefc1c68a44d11330a77629738f741db40957f5c97c5b7072f48c67373fc47794abfda6e35af8893df9785", "size": ! "6392588"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:21.380406", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-07-11T08:36:21.381476", "lvl": "debug", "msg": "start: 1.5 prepare-tftp-overlay (timeout 00:09:53) [common]"}
- {"dt": "2024-07-11T08:36:21.382508", "lvl": "debug", "msg": "start: 1.5.1 extract-nfsrootfs (timeout 00:09:53) [common]"}
- {"dt": "2024-07-11T08:36:21.383496", "lvl": "debug", "msg": "end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:21.384530", "lvl": "debug", "msg": "start: 1.5.2 lava-overlay (timeout 00:09:53) [common]"}
- {"dt": "2024-07-11T08:36:21.385435", "lvl": "info", "msg": "[common] skipped lava-overlay - no test action."}
- {"dt": "2024-07-11T08:36:21.386425", "lvl": "debug", "msg": "end: 1.5.2 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:21.387429", "lvl": "debug", "msg": "start: 1.5.3 extract-overlay-ramdisk (timeout 00:09:53) [common]"}
- {"dt": "2024-07-11T08:36:22.364115", "lvl": "debug", "msg": "end: 1.5.3 extract-overlay-ramdisk (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T08:36:22.365393", "lvl": "debug", "msg": "start: 1.5.4 extract-modules (timeout 00:09:52) [common]"}
- {"dt": "2024-07-11T08:36:22.366305", "lvl": "info", "msg": "extracting modules file /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/modules/modules.tar to /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk"}
- {"dt": "2024-07-11T08:36:23.856861", "lvl": "debug", "msg": "end: 1.5.4 extract-modules (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T08:36:23.857672", "lvl": "debug", "msg": "start: 1.5.5 apply-overlay-tftp (timeout 00:09:51) [common]"}
- {"dt": "2024-07-11T08:36:23.858319", "lvl": "debug", "msg": "end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:23.858862", "lvl": "debug", "msg": "start: 1.5.6 prepare-kernel (timeout 00:09:51) [common]"}
- {"dt": "2024-07-11T08:36:23.859399", "lvl": "debug", "msg": "start: 1.5.6.1 uboot-prepare-kernel (timeout 00:09:51) [common]"}
- {"dt": "2024-07-11T08:36:23.859959", "lvl": "debug", "msg": "end: 1.5.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:23.860649", "lvl": "debug", "msg": "end: 1.5.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:23.861205", "lvl": "debug", "msg": "start: 1.5.7 configure-preseed-file (timeout 00:09:51) [common]"}
- {"dt": "2024-07-11T08:36:23.861738", "lvl": "debug", "msg": "end: 1.5.7 configure-preseed-file (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:23.862254", "lvl": "debug", "msg": "start: 1.5.8 compress-ramdisk (timeout 00:09:51) [common]"}
- {"dt": "2024-07-11T08:36:23.862690", "lvl": "info", "msg": "Building ramdisk /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk"}
- {"dt": "2024-07-11T08:36:26.209379", "lvl": "debug", "msg": ">> 90939 blocks\n"}
- {"dt": "2024-07-11T08:36:32.117109", "lvl": "debug", "msg": "Adding RAMdisk u-boot header."}
- {"dt": "2024-07-11T08:36:32.119024", "lvl": "debug", "msg": "mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:36:32.311282", "lvl": "debug", "msg": "output: Image Name:   "}
- {"dt": "2024-07-11T08:36:32.312965", "lvl": "debug", "msg": "output: Created:      Thu Jul 11 08:36:32 2024"}
- {"dt": "2024-07-11T08:36:32.314417", "lvl": "debug", "msg": "output: Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-07-11T08:36:32.315801", "lvl": "debug", "msg": "output: Data Size:    13779951 Bytes = 13456.98 KiB = 13.14 MiB"}
- {"dt": "2024-07-11T08:36:32.317267", "lvl": "debug", "msg": "output: Load Address: 00000000"}
- {"dt": "2024-07-11T08:36:32.318618", "lvl": "debug", "msg": "output: Entry Point:  00000000"}
- {"dt": "2024-07-11T08:36:32.319905", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-07-11T08:36:32.321976", "lvl": "debug", "msg": "rename /var/lib/lava/dispatcher/tmp/35452/extract-overlay-ramdisk-ay80h60h/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:36:32.324284", "lvl": "debug", "msg": "end: 1.5.8 compress-ramdisk (duration 00:00:08) [common]"}
- {"dt": "2024-07-11T08:36:32.325256", "lvl": "debug", "msg": "end: 1.5 prepare-tftp-overlay (duration 00:00:11) [common]"}
- {"dt": "2024-07-11T08:36:32.325796", "lvl": "debug", "msg": "start: 1.6 lxc-create-udev-rule-action (timeout 00:09:42) [common]"}
- {"dt": "2024-07-11T08:36:32.326267", "lvl": "debug", "msg": "No LXC device requested"}
- {"dt": "2024-07-11T08:36:32.326783", "lvl": "debug", "msg": "end: 1.6 lxc-create-udev-rule-action (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:32.327192", "lvl": "debug", "msg": "Checking files for TFTP limit of 4294967296 bytes."}
- {"dt": "2024-07-11T08:36:32.330084", "lvl": "info", "msg": "end: 1 tftp-deploy (duration 00:00:18) [common]"}
- {"dt": "2024-07-11T08:36:32.330763", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T08:36:32.331307", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T08:36:32.331806", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:32.332377", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T08:36:32.333170", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-07-11T08:36:32.333614", "lvl": "debug", "msg": "- {BOOTX}: booti 0x00080000 0x02700000 0x02400000"}
- {"dt": "2024-07-11T08:36:32.334009", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x02400000"}
- {"dt": "2024-07-11T08:36:32.334391", "lvl": "debug", "msg": "- {DTB}: 35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb"}
- {"dt": "2024-07-11T08:36:32.334771", "lvl": "debug", "msg": "- {INITRD}: 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:36:32.335150", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x00080000"}
- {"dt": "2024-07-11T08:36:32.335523", "lvl": "debug", "msg": "- {KERNEL}: 35452/tftp-deploy-jnibuy3n/kernel/Image"}
- {"dt": "2024-07-11T08:36:32.335897", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-07-11T08:36:32.336285", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-07-11T08:36:32.336763", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-07-11T08:36:32.337216", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x02700000"}
- {"dt": "2024-07-11T08:36:32.337651", "lvl": "debug", "msg": "- {RAMDISK}: 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:36:32.338078", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-07-11T08:36:32.338503", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-07-11T08:36:32.338923", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-07-11T08:36:32.339340", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-07-11T08:36:32.339760", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-07-11T08:36:32.340180", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-07-11T08:36:32.340642", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-07-11T08:36:32.341070", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:32.341492", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:32.341912", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-07-11T08:36:32.342333", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-07-11T08:36:32.342746", "lvl": "info", "msg": "- tftp 0x00080000 35452/tftp-deploy-jnibuy3n/kernel/Image"}
- {"dt": "2024-07-11T08:36:32.343163", "lvl": "info", "msg": "- tftp 0x02700000 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:36:32.343587", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-07-11T08:36:32.344004", "lvl": "info", "msg": "- tftp 0x02400000 35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb"}
- {"dt": "2024-07-11T08:36:32.344454", "lvl": "info", "msg": "- setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-07-11T08:36:32.344880", "lvl": "info", "msg": "- fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000"}
- {"dt": "2024-07-11T08:36:32.345459", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T08:36:32.345951", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.01", "extra": {"dtb_addr": "0x02400000", "kernel_addr": "0x00080000", "ramdisk_addr": "0x02700000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:32.347190", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T08:36:32.347705", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63003'"}
- {"dt": "2024-07-11T08:36:35.468666", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-07-11T08:36:35.470991", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T08:36:35.472149", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:04:57) [common]"}
- {"dt": "2024-07-11T08:36:35.474696", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:04:57) [common]"}
- {"dt": "2024-07-11T08:36:35.475804", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:04:57) [common]"}
- {"dt": "2024-07-11T08:36:35.477050", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '7'"}
- {"dt": "2024-07-11T08:36:38.897745", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-07-11T08:36:39.964774", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-07-11T08:36:40.007723", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2024-07-11T08:36:40.111743", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2024-07-11T08:36:40.113887", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.64", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:36:40.117731", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2024-07-11T08:36:40.119975", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:04:52) [common]"}
- {"dt": "2024-07-11T08:36:40.121238", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-07-11T08:36:40.121763", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:05:00)"}
- {"dt": "2024-07-11T08:36:40.123179", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-07-11T08:36:40.123739", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-07-11T08:36:40.124187", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-07-11T08:36:46.571990", "lvl": "target", "msg": "\0"}
- {"dt": "2024-07-11T08:36:46.573869", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T08:36:46.576159", "lvl": "target", "msg": "U-Boot 2020.07-00717-gfee68b98fe-dirty (Jul 17 2020 - 09:59:08 +0200)"}
- {"dt": "2024-07-11T08:36:46.577899", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T08:36:46.717824", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2024-07-11T08:36:46.749939", "lvl": "target", "msg": "RPI 4 Model B (0xc03111)"}
- {"dt": "2024-07-11T08:36:46.786643", "lvl": "target", "msg": "MMC:   emmc2@7e340000: 0, mmcnr@7e300000: 1"}
- {"dt": "2024-07-11T08:36:46.852944", "lvl": "target", "msg": "Loading Environment from FAT... *** Warning - bad CRC, using default environment"}
- {"dt": "2024-07-11T08:36:46.854474", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T08:36:46.880733", "lvl": "target", "msg": "In:    serial"}
- {"dt": "2024-07-11T08:36:46.882407", "lvl": "target", "msg": "Out:   serial"}
- {"dt": "2024-07-11T08:36:46.883970", "lvl": "target", "msg": "Err:   serial"}
- {"dt": "2024-07-11T08:36:46.886332", "lvl": "target", "msg": "Net:   eth0: genet@7d580000"}
- {"dt": "2024-07-11T08:36:46.888009", "lvl": "target", "msg": "starting USB..."}
- {"dt": "2024-07-11T08:36:46.889661", "lvl": "target", "msg": "No working controllers found"}
- {"dt": "2024-07-11T08:36:46.891932", "lvl": "input", "msg": "c"}
- {"dt": "2024-07-11T08:36:46.892806", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:07) [common]"}
- {"dt": "2024-07-11T08:36:46.893535", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:04:45) [common]"}
- {"dt": "2024-07-11T08:36:46.894105", "lvl": "debug", "msg": "Setting prompt string to ['U-Boot>']"}
- {"dt": "2024-07-11T08:36:46.894652", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>'] (timeout 00:04:45)"}
- {"dt": "2024-07-11T08:36:46.914497", "lvl": "target", "msg": "Hit any key to stop autoboot:  2 \x03\b\b\b 0 "}
- {"dt": "2024-07-11T08:36:46.915788", "lvl": "debug", "msg": "Setting prompt string to ['U-Boot>', '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-07-11T08:36:46.916783", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-07-11T08:36:47.018225", "lvl": "target", "msg": "U-Boot> setenv autoload no"}
- {"dt": "2024-07-11T08:36:47.019957", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:47.030537", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-07-11T08:36:47.031444", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2024-07-11T08:36:47.132456", "lvl": "target", "msg": "U-Boot> setenv initrd_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:47.134526", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:47.138716", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:47.140921", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2024-07-11T08:36:47.243167", "lvl": "target", "msg": "U-Boot> setenv fdt_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:47.245403", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:47.250685", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2024-07-11T08:36:47.252899", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-07-11T08:36:47.355132", "lvl": "target", "msg": "U-Boot> dhcp"}
- {"dt": "2024-07-11T08:36:47.357399", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:47.362630", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-07-11T08:36:50.528815", "lvl": "target", "msg": "genet@7d580000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2024-07-11T08:36:50.529811", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-07-11T08:36:50.784822", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-07-11T08:36:51.280453", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-07-11T08:36:51.819916", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.241 (1283 ms)"}
- {"dt": "2024-07-11T08:36:51.821663", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-07-11T08:36:51.923296", "lvl": "target", "msg": "U-Boot> setenv serverip 192.168.66.1"}
- {"dt": "2024-07-11T08:36:51.924412", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:51.932174", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-07-11T08:36:51.933734", "lvl": "input", "msg": "tftp 0x00080000 35452/tftp-deploy-jnibuy3n/kernel/Image\n"}
- {"dt": "2024-07-11T08:36:52.035336", "lvl": "target", "msg": "U-Boot> tftp 0x00080000 35452/tftp-deploy-jnibuy3n/kernel/Image"}
- {"dt": "2024-07-11T08:36:52.037462", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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-07-11T08:36:52.044112", "lvl": "target", "msg": "tftp 0x00080000 35452/tftp-deploy-jnibuy3n/kernel/Image"}
- {"dt": "2024-07-11T08:36:52.045264", "lvl": "target", "msg": "Using genet@7d580000 device"}
- {"dt": "2024-07-11T08:36:52.068007", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.241"}
- {"dt": "2024-07-11T08:36:52.068929", "lvl": "target", "msg": "Filename '35452/tftp-deploy-jnibuy3n/kernel/Image'."}
- {"dt": "2024-07-11T08:36:52.070300", "lvl": "target", "msg": "Load address: 0x80000"}
- {"dt": "2024-07-11T08:37:11.389993", "lvl": "target", "msg": "Loading: *\bT #T #################################################  26 MiB"}
- {"dt": "2024-07-11T08:37:11.390684", "lvl": "target", "msg": "\t 1.3 MiB/s"}
- {"dt": "2024-07-11T08:37:11.391157", "lvl": "target", "msg": "done"}
- {"dt": "2024-07-11T08:37:11.393404", "lvl": "target", "msg": "Bytes transferred = 27247104 (19fc200 hex)"}
- {"dt": "2024-07-11T08:37:11.394791", "lvl": "input", "msg": "tftp 0x02700000 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot\n"}
- {"dt": "2024-07-11T08:37:11.496285", "lvl": "target", "msg": "U-Boot> tftp 0x02700000 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:37:11.498387", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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:21)"}
- {"dt": "2024-07-11T08:37:11.503034", "lvl": "target", "msg": "tftp 0x02700000 35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot"}
- {"dt": "2024-07-11T08:37:11.544890", "lvl": "target", "msg": "Using genet@7d580000 device"}
- {"dt": "2024-07-11T08:37:11.545459", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.241"}
- {"dt": "2024-07-11T08:37:11.545852", "lvl": "target", "msg": "Filename '35452/tftp-deploy-jnibuy3n/ramdisk/ramdisk.cpio.gz.uboot'."}
- {"dt": "2024-07-11T08:37:11.546227", "lvl": "target", "msg": "Load address: 0x2700000"}
- {"dt": "2024-07-11T08:37:16.433523", "lvl": "target", "msg": "Loading: *\b##################################################  13.1 MiB"}
- {"dt": "2024-07-11T08:37:16.435299", "lvl": "target", "msg": "\t 2.7 MiB/s"}
- {"dt": "2024-07-11T08:37:16.436957", "lvl": "target", "msg": "done"}
- {"dt": "2024-07-11T08:37:16.438997", "lvl": "target", "msg": "Bytes transferred = 13780015 (d2442f hex)"}
- {"dt": "2024-07-11T08:37:16.440081", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-07-11T08:37:16.541428", "lvl": "target", "msg": "U-Boot> setenv initrd_size ${filesize}"}
- {"dt": "2024-07-11T08:37:16.543413", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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:16)"}
- {"dt": "2024-07-11T08:37:16.547425", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-07-11T08:37:16.563753", "lvl": "input", "msg": "tftp 0x02400000 35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb\n"}
- {"dt": "2024-07-11T08:37:16.665189", "lvl": "target", "msg": "U-Boot> tftp 0x02400000 35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb"}
- {"dt": "2024-07-11T08:37:16.667090", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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:16)"}
- {"dt": "2024-07-11T08:37:16.670936", "lvl": "target", "msg": "tftp 0x02400000 35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb"}
- {"dt": "2024-07-11T08:37:16.722217", "lvl": "target", "msg": "Using genet@7d580000 device"}
- {"dt": "2024-07-11T08:37:16.724584", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.241"}
- {"dt": "2024-07-11T08:37:16.726250", "lvl": "target", "msg": "Filename '35452/tftp-deploy-jnibuy3n/dtb/bcm2711-rpi-4-b.dtb'."}
- {"dt": "2024-07-11T08:37:16.727858", "lvl": "target", "msg": "Load address: 0x2400000"}
- {"dt": "2024-07-11T08:37:16.729479", "lvl": "target", "msg": "Loading: *\b##################################################  21.7 KiB"}
- {"dt": "2024-07-11T08:37:16.731358", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2024-07-11T08:37:16.732850", "lvl": "target", "msg": "done"}
- {"dt": "2024-07-11T08:37:16.733281", "lvl": "target", "msg": "Bytes transferred = 22210 (56c2 hex)"}
- {"dt": "2024-07-11T08:37:16.734012", "lvl": "input", "msg": "setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'\n"}
- {"dt": "2024-07-11T08:37:16.835016", "lvl": "target", "msg": "U-Boot> setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-07-11T08:37:16.837061", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['U-Boot>', '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:15)"}
- {"dt": "2024-07-11T08:37:16.841096", "lvl": "target", "msg": "setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp'"}
- {"dt": "2024-07-11T08:37:16.885484", "lvl": "input", "msg": "fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000\n"}
- {"dt": "2024-07-11T08:37:16.987682", "lvl": "target", "msg": "U-Boot> fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000"}
- {"dt": "2024-07-11T08:37:16.989733", "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-07-11T08:37:16.991503", "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:15)"}
- {"dt": "2024-07-11T08:37:16.994935", "lvl": "target", "msg": "fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000"}
- {"dt": "2024-07-11T08:37:17.036992", "lvl": "target", "msg": "libfdt fdt_path_offset() returned FDT_ERR_BADPATH"}
- {"dt": "2024-07-11T08:37:17.037663", "lvl": "target", "msg": "## Loading init Ramdisk from Legacy Image at 02700000 ..."}
- {"dt": "2024-07-11T08:37:17.038157", "lvl": "target", "msg": "   Image Name:   "}
- {"dt": "2024-07-11T08:37:17.039234", "lvl": "target", "msg": "   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)"}
- {"dt": "2024-07-11T08:37:17.040513", "lvl": "target", "msg": "   Data Size:    13779951 Bytes = 13.1 MiB"}
- {"dt": "2024-07-11T08:37:17.041678", "lvl": "target", "msg": "   Load Address: 00000000"}
- {"dt": "2024-07-11T08:37:17.042848", "lvl": "target", "msg": "   Entry Point:  00000000"}
- {"dt": "2024-07-11T08:37:17.191104", "lvl": "target", "msg": "   Verifying Checksum ... OK"}
- {"dt": "2024-07-11T08:37:17.209748", "lvl": "target", "msg": "## Flattened Device Tree blob at 02400000"}
- {"dt": "2024-07-11T08:37:17.211404", "lvl": "target", "msg": "   Booting using the fdt blob at 0x2400000"}
- {"dt": "2024-07-11T08:37:17.291858", "lvl": "target", "msg": "   Loading Ramdisk to 3a225000, end 3af493ef ... OK"}
- {"dt": "2024-07-11T08:37:17.312832", "lvl": "target", "msg": "   Loading Device Tree to 000000003a21c000, end 000000003a224fff ... OK"}
- {"dt": "2024-07-11T08:37:17.314494", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T08:37:17.316817", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-07-11T08:37:17.318231", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T08:37:17.320776", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:30) [common]"}
- {"dt": "2024-07-11T08:37:17.322663", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:04:15) [common]"}
- {"dt": "2024-07-11T08:37:17.324155", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-07-11T08:37:17.325723", "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-07-11T08:37:17.327265", "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-07-11T08:37:19.173235", "lvl": "target", "msg": "<6>[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]"}
- {"dt": "2024-07-11T08:37:19.175129", "lvl": "target", "msg": "<5>[    0.000000] Linux version 5.6.12 (KernelCI@44cce1cf70dc) (gcc version 8.3.0 (Debian 8.3.0-2)) #1 SMP PREEMPT Sun May 10 14:46:40 UTC 2020"}
- {"dt": "2024-07-11T08:37:19.177101", "lvl": "target", "msg": "<6>[    0.000000] Machine model: Raspberry Pi 4 Model B"}
- {"dt": "2024-07-11T08:37:19.178729", "lvl": "target", "msg": "<6>[    0.000000] efi: Getting EFI parameters from FDT:"}
- {"dt": "2024-07-11T08:37:19.180298", "lvl": "target", "msg": "<6>[    0.000000] efi: UEFI not found."}
- {"dt": "2024-07-11T08:37:19.182236", "lvl": "target", "msg": "<6>[    0.000000] Reserved memory: created CMA memory pool at 0x0000000036000000, size 64 MiB"}
- {"dt": "2024-07-11T08:37:19.184726", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:04:13) [common]"}
- {"dt": "2024-07-11T08:37:19.185342", "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-07-11T08:37:19.185844", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-07-11T08:37:19.186382", "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-07-11T08:37:19.186914", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-07-11T08:37:19.187366", "lvl": "debug", "msg": "No login prompt set."}
- {"dt": "2024-07-11T08:37:19.187818", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-07-11T08:37:19.188244", "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-07-11T08:37:19.189174", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:04:13)"}
- {"dt": "2024-07-11T08:37:19.216562", "lvl": "target", "msg": "<6>[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-07-11T08:37:19.217434", "lvl": "target", "msg": "<6>[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-07-11T08:37:19.218308", "lvl": "target", "msg": "<6>[    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000fbffffff]"}
- {"dt": "2024-07-11T08:37:19.219087", "lvl": "target", "msg": "<6>[    0.000000] NUMA: NODE_DATA [mem 0xfb81d100-0xfb81efff]"}
- {"dt": "2024-07-11T08:37:19.219840", "lvl": "target", "msg": "<6>[    0.000000] Zone ranges:"}
- {"dt": "2024-07-11T08:37:19.220633", "lvl": "target", "msg": "<6>[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]"}
- {"dt": "2024-07-11T08:37:19.221500", "lvl": "target", "msg": "<6>[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]"}
- {"dt": "2024-07-11T08:37:19.222286", "lvl": "target", "msg": "<6>[    0.000000]   Normal   empty"}
- {"dt": "2024-07-11T08:37:19.260057", "lvl": "target", "msg": "<6>[    0.000000] Movable zone start for each node"}
- {"dt": "2024-07-11T08:37:19.261806", "lvl": "target", "msg": "<6>[    0.000000] Early memory node ranges"}
- {"dt": "2024-07-11T08:37:19.262925", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]"}
- {"dt": "2024-07-11T08:37:19.263965", "lvl": "target", "msg": "<6>[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]"}
- {"dt": "2024-07-11T08:37:19.265097", "lvl": "target", "msg": "<6>[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]"}
- {"dt": "2024-07-11T08:37:19.266160", "lvl": "target", "msg": "<6>[    0.000000] percpu: Embedded 23 pages/cpu s53272 r8192 d32744 u94208"}
- {"dt": "2024-07-11T08:37:19.267214", "lvl": "target", "msg": "<6>[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-07-11T08:37:19.303959", "lvl": "target", "msg": "<6>[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-07-11T08:37:19.306316", "lvl": "target", "msg": "<4>[    0.000000] ARM_SMCCC_ARCH_WORKAROUND_1 missing from firmware"}
- {"dt": "2024-07-11T08:37:19.307782", "lvl": "target", "msg": "<6>[    0.000000] CPU features: detected: ARM erratum 1319367"}
- {"dt": "2024-07-11T08:37:19.309213", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912"}
- {"dt": "2024-07-11T08:37:19.310588", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: DMA32"}
- {"dt": "2024-07-11T08:37:19.311890", "lvl": "target", "msg": "<5>[    0.000000] Kernel command line: console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp"}
- {"dt": "2024-07-11T08:37:19.313520", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.348406", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.349463", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2024-07-11T08:37:19.350588", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x32000000-0x36000000] (64MB)"}
- {"dt": "2024-07-11T08:37:19.351554", "lvl": "target", "msg": "<6>[    0.000000] Memory: 3798776K/4050944K available (12348K kernel code, 1968K rwdata, 6952K rodata, 5312K init, 462K bss, 186632K reserved, 65536K cma-reserved)"}
- {"dt": "2024-07-11T08:37:19.352724", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-07-11T08:37:19.391511", "lvl": "target", "msg": "<6>[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-07-11T08:37:19.393467", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-07-11T08:37:19.394741", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-07-11T08:37:19.395934", "lvl": "target", "msg": "<6>[    0.000000] \tTasks RCU enabled."}
- {"dt": "2024-07-11T08:37:19.397222", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-07-11T08:37:19.398429", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-07-11T08:37:19.399626", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-07-11T08:37:19.435341", "lvl": "target", "msg": "<6>[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-07-11T08:37:19.437959", "lvl": "target", "msg": "<5>[    0.000000] random: get_random_bytes called from start_kernel+0x2a8/0x434 with crng_init=0"}
- {"dt": "2024-07-11T08:37:19.439398", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-07-11T08:37:19.440852", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-07-11T08:37:19.442233", "lvl": "target", "msg": "<6>[    0.000006] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-07-11T08:37:19.443848", "lvl": "target", "msg": "<6>[    0.000178] Console: colour dummy device 80x25"}
- {"dt": "2024-07-11T08:37:19.479629", "lvl": "target", "msg": "<6>[    0.000266] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-07-11T08:37:19.480702", "lvl": "target", "msg": "<6>[    0.000281] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-07-11T08:37:19.481669", "lvl": "target", "msg": "<6>[    0.000380] LSM: Security Framework initializing"}
- {"dt": "2024-07-11T08:37:19.482793", "lvl": "target", "msg": "<6>[    0.000506] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.483773", "lvl": "target", "msg": "<6>[    0.000577] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.484797", "lvl": "target", "msg": "<6>[    0.032023] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-07-11T08:37:19.485939", "lvl": "target", "msg": "<6>[    0.041791] EFI services will not be available."}
- {"dt": "2024-07-11T08:37:19.523046", "lvl": "target", "msg": "<6>[    0.048063] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-07-11T08:37:19.525383", "lvl": "target", "msg": "<6>[    0.080198] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-07-11T08:37:19.526665", "lvl": "target", "msg": "<6>[    0.080254] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]"}
- {"dt": "2024-07-11T08:37:19.527867", "lvl": "target", "msg": "<6>[    0.112262] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-07-11T08:37:19.529179", "lvl": "target", "msg": "<6>[    0.112301] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]"}
- {"dt": "2024-07-11T08:37:19.530386", "lvl": "target", "msg": "<6>[    0.144345] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-07-11T08:37:19.531573", "lvl": "target", "msg": "<6>[    0.144382] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]"}
- {"dt": "2024-07-11T08:37:19.533017", "lvl": "target", "msg": "<6>[    0.144466] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-07-11T08:37:19.566821", "lvl": "target", "msg": "<6>[    0.144494] SMP: Total of 4 processors activated."}
- {"dt": "2024-07-11T08:37:19.569200", "lvl": "target", "msg": "<6>[    0.144503] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-07-11T08:37:19.570659", "lvl": "target", "msg": "<6>[    0.144513] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-07-11T08:37:19.572028", "lvl": "target", "msg": "<6>[    0.161249] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-07-11T08:37:19.573478", "lvl": "target", "msg": "<6>[    0.161287] alternatives: patching kernel code"}
- {"dt": "2024-07-11T08:37:19.574846", "lvl": "target", "msg": "<6>[    0.162940] devtmpfs: initialized"}
- {"dt": "2024-07-11T08:37:19.576203", "lvl": "target", "msg": "<4>[    0.167372] KASLR disabled due to lack of seed"}
- {"dt": "2024-07-11T08:37:19.577854", "lvl": "target", "msg": "<6>[    0.167777] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-07-11T08:37:19.610385", "lvl": "target", "msg": "<6>[    0.167800] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.611819", "lvl": "target", "msg": "<6>[    0.169609] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-07-11T08:37:19.612825", "lvl": "target", "msg": "<6>[    0.170888] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-07-11T08:37:19.613687", "lvl": "target", "msg": "<6>[    0.170893] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-07-11T08:37:19.614541", "lvl": "target", "msg": "<6>[    0.171093] DMI not present or invalid."}
- {"dt": "2024-07-11T08:37:19.615397", "lvl": "target", "msg": "<6>[    0.171699] NET: Registered protocol family 16"}
- {"dt": "2024-07-11T08:37:19.616265", "lvl": "target", "msg": "<6>[    0.173197] DMA: preallocated 256 KiB pool for atomic allocations"}
- {"dt": "2024-07-11T08:37:19.654476", "lvl": "target", "msg": "<6>[    0.173214] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-07-11T08:37:19.657078", "lvl": "target", "msg": "<5>[    0.173389] audit: type=2000 audit(0.172:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-07-11T08:37:19.658358", "lvl": "target", "msg": "<6>[    0.174515] cpuidle: using governor menu"}
- {"dt": "2024-07-11T08:37:19.659556", "lvl": "target", "msg": "<6>[    0.174784] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-07-11T08:37:19.660837", "lvl": "target", "msg": "<6>[    0.174874] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-07-11T08:37:19.662051", "lvl": "target", "msg": "<6>[    0.176123] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-07-11T08:37:19.663236", "lvl": "target", "msg": "<6>[    0.196046] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T08:37:19.698244", "lvl": "target", "msg": "<6>[    0.196060] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T08:37:19.700211", "lvl": "target", "msg": "<6>[    0.196071] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T08:37:19.701195", "lvl": "target", "msg": "<6>[    0.196080] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T08:37:19.702056", "lvl": "target", "msg": "<6>[    0.198731] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-07-11T08:37:19.702943", "lvl": "target", "msg": "<6>[    0.203566] ACPI: Interpreter disabled."}
- {"dt": "2024-07-11T08:37:19.703815", "lvl": "target", "msg": "<6>[    0.204799] iommu: Default domain type: Translated "}
- {"dt": "2024-07-11T08:37:19.704745", "lvl": "target", "msg": "<6>[    0.205020] vgaarb: loaded"}
- {"dt": "2024-07-11T08:37:19.705619", "lvl": "target", "msg": "<5>[    0.205337] SCSI subsystem initialized"}
- {"dt": "2024-07-11T08:37:19.706629", "lvl": "target", "msg": "<6>[    0.205729] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-07-11T08:37:19.742107", "lvl": "target", "msg": "<6>[    0.205772] usbcore: registered new interface driver hub"}
- {"dt": "2024-07-11T08:37:19.744510", "lvl": "target", "msg": "<6>[    0.205843] usbcore: registered new device driver usb"}
- {"dt": "2024-07-11T08:37:19.745644", "lvl": "target", "msg": "<4>[    0.206000] usb_phy_generic phy: phy supply vcc not found, using dummy regulator"}
- {"dt": "2024-07-11T08:37:19.746678", "lvl": "target", "msg": "<6>[    0.206599] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-07-11T08:37:19.747730", "lvl": "target", "msg": "<6>[    0.206608] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-07-11T08:37:19.748900", "lvl": "target", "msg": "<6>[    0.206626] PTP clock support registered"}
- {"dt": "2024-07-11T08:37:19.750147", "lvl": "target", "msg": "<6>[    0.206745] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-07-11T08:37:19.751253", "lvl": "target", "msg": "<6>[    0.207909] FPGA manager framework"}
- {"dt": "2024-07-11T08:37:19.787998", "lvl": "target", "msg": "<6>[    0.207989] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-07-11T08:37:19.789889", "lvl": "target", "msg": "<6>[    0.208733] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-07-11T08:37:19.791482", "lvl": "target", "msg": "<5>[    0.208962] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-07-11T08:37:19.793154", "lvl": "target", "msg": "<6>[    0.209025] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-07-11T08:37:19.794730", "lvl": "target", "msg": "<6>[    0.209220] pnp: PnP ACPI: disabled"}
- {"dt": "2024-07-11T08:37:19.796290", "lvl": "target", "msg": "<6>[    0.215583] NET: Registered protocol family 2"}
- {"dt": "2024-07-11T08:37:19.798232", "lvl": "target", "msg": "<6>[    0.216027] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.830942", "lvl": "target", "msg": "<6>[    0.216066] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.831996", "lvl": "target", "msg": "<6>[    0.216251] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.833027", "lvl": "target", "msg": "<6>[    0.216673] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-07-11T08:37:19.833970", "lvl": "target", "msg": "<6>[    0.217009] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.834927", "lvl": "target", "msg": "<6>[    0.217063] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T08:37:19.836034", "lvl": "target", "msg": "<6>[    0.217260] NET: Registered protocol family 1"}
- {"dt": "2024-07-11T08:37:19.873482", "lvl": "target", "msg": "<6>[    0.217786] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-07-11T08:37:19.876813", "lvl": "target", "msg": "<6>[    0.217797] RPC: Registered udp transport module."}
- {"dt": "2024-07-11T08:37:19.878290", "lvl": "target", "msg": "<6>[    0.217804] RPC: Registered tcp transport module."}
- {"dt": "2024-07-11T08:37:19.879646", "lvl": "target", "msg": "<6>[    0.217811] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-07-11T08:37:19.881060", "lvl": "target", "msg": "<6>[    0.217824] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-07-11T08:37:19.882423", "lvl": "target", "msg": "<6>[    0.218002] Unpacking initramfs..."}
- {"dt": "2024-07-11T08:37:19.883789", "lvl": "target", "msg": "<6>[    1.261573] Freeing initrd memory: 13456K"}
- {"dt": "2024-07-11T08:37:19.884945", "lvl": "target", "msg": "<6>[    1.262440] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available"}
- {"dt": "2024-07-11T08:37:19.917338", "lvl": "target", "msg": "<6>[    1.262561] kvm [1]: IPA Size Limit: 44bits"}
- {"dt": "2024-07-11T08:37:19.918201", "lvl": "target", "msg": "<6>[    1.263526] kvm [1]: vgic interrupt IRQ1"}
- {"dt": "2024-07-11T08:37:19.920252", "lvl": "target", "msg": "<6>[    1.263674] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-07-11T08:37:19.921181", "lvl": "target", "msg": "<5>[    1.337831] Initialise system trusted keyrings"}
- {"dt": "2024-07-11T08:37:19.921960", "lvl": "target", "msg": "<6>[    1.337995] workingset: timestamp_bits=44 max_order=20 bucket_order=0"}
- {"dt": "2024-07-11T08:37:19.922776", "lvl": "target", "msg": "<6>[    1.345082] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-07-11T08:37:19.923588", "lvl": "target", "msg": "<5>[    1.345880] NFS: Registering the id_resolver key type"}
- {"dt": "2024-07-11T08:37:19.924428", "lvl": "target", "msg": "<5>[    1.345909] Key type id_resolver registered"}
- {"dt": "2024-07-11T08:37:19.925393", "lvl": "target", "msg": "<5>[    1.345918] Key type id_legacy registered"}
- {"dt": "2024-07-11T08:37:19.962504", "lvl": "target", "msg": "<6>[    1.345935] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-07-11T08:37:19.963469", "lvl": "target", "msg": "<6>[    1.346099] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-07-11T08:37:19.964267", "lvl": "target", "msg": "<5>[    1.381288] Key type asymmetric registered"}
- {"dt": "2024-07-11T08:37:19.965127", "lvl": "target", "msg": "<5>[    1.381303] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-07-11T08:37:19.965929", "lvl": "target", "msg": "<6>[    1.381371] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-07-11T08:37:19.966955", "lvl": "target", "msg": "<6>[    1.381381] io scheduler mq-deadline registered"}
- {"dt": "2024-07-11T08:37:19.967986", "lvl": "target", "msg": "<6>[    1.381389] io scheduler kyber registered"}
- {"dt": "2024-07-11T08:37:19.969279", "lvl": "target", "msg": "<4>[    1.391589] EINJ: ACPI disabled."}
- {"dt": "2024-07-11T08:37:20.017271", "lvl": "target", "msg": "<6>[    1.405258] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-07-11T08:37:20.020480", "lvl": "target", "msg": "<6>[    1.406975] printk: console [ttyS1] disabled"}
- {"dt": "2024-07-11T08:37:20.021910", "lvl": "target", "msg": "<6>[    1.407061] fe215040.serial: ttyS1 at MMIO 0xfe215040 (irq = 18, base_baud = 62499999) is a 16550"}
- {"dt": "2024-07-11T08:37:20.023280", "lvl": "target", "msg": "<6>[    2.258018] printk: console [ttyS1] enabled"}
- {"dt": "2024-07-11T08:37:20.024703", "lvl": "target", "msg": "<6>[    2.263581] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-07-11T08:37:20.026082", "lvl": "target", "msg": "<6>[    2.268966] msm_serial: driver initialized"}
- {"dt": "2024-07-11T08:37:20.027433", "lvl": "target", "msg": "<4>[    2.274751] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-07-11T08:37:20.028921", "lvl": "target", "msg": "<6>[    2.289413] loop: module loaded"}
- {"dt": "2024-07-11T08:37:20.069603", "lvl": "target", "msg": "<6>[    2.293924] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-07-11T08:37:20.071117", "lvl": "target", "msg": "<6>[    2.306102] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2024-07-11T08:37:20.072570", "lvl": "target", "msg": "<6>[    2.310827] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-07-11T08:37:20.073937", "lvl": "target", "msg": "<6>[    2.316970] thunder_xcv, ver 1.0"}
- {"dt": "2024-07-11T08:37:20.075283", "lvl": "target", "msg": "<6>[    2.320540] thunder_bgx, ver 1.0"}
- {"dt": "2024-07-11T08:37:20.076677", "lvl": "target", "msg": "<6>[    2.324126] nicpf, ver 1.0"}
- {"dt": "2024-07-11T08:37:20.077153", "lvl": "target", "msg": "<6>[    2.327682] hclge is initializing"}
- {"dt": "2024-07-11T08:37:20.077569", "lvl": "target", "msg": "<6>[    2.331512] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-07-11T08:37:20.078085", "lvl": "target", "msg": "<6>[    2.339118] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-07-11T08:37:20.112771", "lvl": "target", "msg": "<6>[    2.344836] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k"}
- {"dt": "2024-07-11T08:37:20.115027", "lvl": "target", "msg": "<6>[    2.351027] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-07-11T08:37:20.115935", "lvl": "target", "msg": "<6>[    2.357344] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k"}
- {"dt": "2024-07-11T08:37:20.116872", "lvl": "target", "msg": "<6>[    2.364682] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-07-11T08:37:20.117843", "lvl": "target", "msg": "<6>[    2.370650] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k"}
- {"dt": "2024-07-11T08:37:20.118792", "lvl": "target", "msg": "<6>[    2.378869] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-07-11T08:37:20.119915", "lvl": "target", "msg": "<6>[    2.385472] sky2: driver version 1.30"}
- {"dt": "2024-07-11T08:37:20.158474", "lvl": "target", "msg": "<6>[    2.390120] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-07-11T08:37:20.161667", "lvl": "target", "msg": "<6>[    2.397284] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-07-11T08:37:20.162943", "lvl": "target", "msg": "<6>[    2.404186] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-07-11T08:37:20.164140", "lvl": "target", "msg": "<6>[    2.408997] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-07-11T08:37:20.165402", "lvl": "target", "msg": "<6>[    2.414683] ehci-orion: EHCI orion driver"}
- {"dt": "2024-07-11T08:37:20.166585", "lvl": "target", "msg": "<6>[    2.419116] ehci-exynos: EHCI Exynos driver"}
- {"dt": "2024-07-11T08:37:20.167753", "lvl": "target", "msg": "<6>[    2.423723] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-07-11T08:37:20.168939", "lvl": "target", "msg": "<6>[    2.430280] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-07-11T08:37:20.212030", "lvl": "target", "msg": "<6>[    2.435093] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-07-11T08:37:20.214164", "lvl": "target", "msg": "<6>[    2.440789] ohci-exynos: OHCI Exynos driver"}
- {"dt": "2024-07-11T08:37:20.215053", "lvl": "target", "msg": "<6>[    2.445736] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-07-11T08:37:20.215839", "lvl": "target", "msg": "<6>[    2.454722] i2c /dev entries driver"}
- {"dt": "2024-07-11T08:37:20.216675", "lvl": "target", "msg": "<6>[    2.462963] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-07-11T08:37:20.217467", "lvl": "target", "msg": "<6>[    2.470653] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-07-11T08:37:20.218296", "lvl": "target", "msg": "<6>[    2.477205] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-07-11T08:37:20.219253", "lvl": "target", "msg": "<6>[    2.482287] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-07-11T08:37:20.264600", "lvl": "target", "msg": "<6>[    2.489605] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-07-11T08:37:20.267186", "lvl": "target", "msg": "<6>[    2.497841] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-07-11T08:37:20.268246", "lvl": "target", "msg": "<6>[    2.505714] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-07-11T08:37:20.269255", "lvl": "target", "msg": "<6>[    2.511647] usbhid: USB HID core driver"}
- {"dt": "2024-07-11T08:37:20.270226", "lvl": "target", "msg": "<6>[    2.516642] bcm2835-mbox fe00b880.mailbox: mailbox enabled"}
- {"dt": "2024-07-11T08:37:20.271168", "lvl": "target", "msg": "<6>[    2.525860] NET: Registered protocol family 17"}
- {"dt": "2024-07-11T08:37:20.272109", "lvl": "target", "msg": "<6>[    2.530841] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-07-11T08:37:20.273307", "lvl": "target", "msg": "<5>[    2.535498] Key type dns_resolver registered"}
- {"dt": "2024-07-11T08:37:20.327789", "lvl": "target", "msg": "<6>[    2.540419] registered taskstats version 1"}
- {"dt": "2024-07-11T08:37:20.329791", "lvl": "target", "msg": "<5>[    2.544857] Loading compiled-in X.509 certificates"}
- {"dt": "2024-07-11T08:37:20.330739", "lvl": "target", "msg": "<6>[    2.554483] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 16, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-07-11T08:37:20.331607", "lvl": "target", "msg": "<6>[    2.563949] serial serial0: tty port ttyAMA0 registered"}
- {"dt": "2024-07-11T08:37:20.332535", "lvl": "target", "msg": "<6>[    2.579575] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-01-22 17:23"}
- {"dt": "2024-07-11T08:37:20.333420", "lvl": "target", "msg": "<4>[    2.592507] dwc2 fe980000.usb: fe980000.usb supply vusb_d not found, using dummy regulator"}
- {"dt": "2024-07-11T08:37:20.345789", "lvl": "target", "msg": "<4>[    2.601292] dwc2 fe980000.usb: fe980000.usb supply vusb_a not found, using dummy regulator"}
- {"dt": "2024-07-11T08:37:20.463721", "lvl": "target", "msg": "<6>[    2.710803] dwc2 fe980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM"}
- {"dt": "2024-07-11T08:37:20.466224", "lvl": "target", "msg": "<6>[    2.722138] sdhci-iproc fe300000.sdhci: allocated mmc-pwrseq"}
- {"dt": "2024-07-11T08:37:20.505782", "lvl": "target", "msg": "<6>[    2.760293] mmc0: SDHCI controller on fe300000.sdhci [fe300000.sdhci] using PIO"}
- {"dt": "2024-07-11T08:37:20.573192", "lvl": "target", "msg": "<4>[    2.786283] mmc0: queuing unknown CIS tuple 0x80 (2 bytes)"}
- {"dt": "2024-07-11T08:37:20.574669", "lvl": "target", "msg": "<4>[    2.793819] mmc0: queuing unknown CIS tuple 0x80 (3 bytes)"}
- {"dt": "2024-07-11T08:37:20.576198", "lvl": "target", "msg": "<4>[    2.801343] mmc0: queuing unknown CIS tuple 0x80 (3 bytes)"}
- {"dt": "2024-07-11T08:37:20.577874", "lvl": "target", "msg": "<4>[    2.810358] mmc0: queuing unknown CIS tuple 0x80 (7 bytes)"}
- {"dt": "2024-07-11T08:37:20.579455", "lvl": "target", "msg": "<4>[    2.817876] mmc0: queuing unknown CIS tuple 0x80 (3 bytes)"}
- {"dt": "2024-07-11T08:37:20.581078", "lvl": "target", "msg": "<6>[    2.823737] mmc1: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA"}
- {"dt": "2024-07-11T08:37:20.582923", "lvl": "target", "msg": "<6>[    2.833332] hctosys: unable to open rtc device (rtc0)"}
- {"dt": "2024-07-11T08:37:20.621443", "lvl": "target", "msg": "<5>[    2.884946] random: fast init done"}
- {"dt": "2024-07-11T08:37:20.646644", "lvl": "target", "msg": "<6>[    2.901043] mmc0: new high speed SDIO card at address 0001"}
- {"dt": "2024-07-11T08:37:20.694337", "lvl": "target", "msg": "<6>[    2.943924] mmc1: new ultra high speed DDR50 SDHC card at address 59b4"}
- {"dt": "2024-07-11T08:37:20.696294", "lvl": "target", "msg": "<6>[    2.951483] mmcblk1: mmc1:59b4 USDU1 7.51 GiB "}
- {"dt": "2024-07-11T08:37:20.698016", "lvl": "target", "msg": "<6>[    2.957931]  mmcblk1: p1 p2"}
- {"dt": "2024-07-11T08:37:32.861460", "lvl": "target", "msg": "<6>[   15.132787] ALSA device list:"}
- {"dt": "2024-07-11T08:37:32.885097", "lvl": "target", "msg": "<6>[   15.136066]   No soundcards found."}
- {"dt": "2024-07-11T08:37:32.888699", "lvl": "target", "msg": "<6>[   15.144815] Freeing unused kernel memory: 5312K"}
- {"dt": "2024-07-11T08:37:32.890027", "lvl": "target", "msg": "<6>[   15.149881] Run /init as init process"}
- {"dt": "2024-07-11T08:37:32.919316", "lvl": "target", "msg": "Starting syslogd: OK"}
- {"dt": "2024-07-11T08:37:32.986003", "lvl": "target", "msg": "Starting klogd: OK"}
- {"dt": "2024-07-11T08:37:32.989922", "lvl": "target", "msg": "Populating /dev using udev: <30>[   15.206857] udevd[149]: starting version 3.2.7"}
- {"dt": "2024-07-11T08:37:32.991531", "lvl": "target", "msg": "<5>[   15.212496] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-07-11T08:37:32.993116", "lvl": "target", "msg": "<5>[   15.219514] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-07-11T08:37:32.994515", "lvl": "target", "msg": "<5>[   15.226379] random: udevd: uninitialized urandom read (16 bytes read)"}
- {"dt": "2024-07-11T08:37:32.995896", "lvl": "target", "msg": "<27>[   15.235372] udevd[149]: specified group 'kvm' unknown"}
- {"dt": "2024-07-11T08:37:32.996977", "lvl": "target", "msg": "<30>[   15.246040] udevd[150]: starting eudev-3.2.7"}
- {"dt": "2024-07-11T08:37:33.192751", "lvl": "target", "msg": "<4>[   15.420947] bcmgenet fd580000.ethernet: failed to get enet clock"}
- {"dt": "2024-07-11T08:37:33.196874", "lvl": "target", "msg": "<6>[   15.428802] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000"}
- {"dt": "2024-07-11T08:37:33.198558", "lvl": "target", "msg": "<6>[   15.429164] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:"}
- {"dt": "2024-07-11T08:37:33.200161", "lvl": "target", "msg": "<4>[   15.442551] bcmgenet fd580000.ethernet: failed to get enet-wol clock"}
- {"dt": "2024-07-11T08:37:33.201819", "lvl": "target", "msg": "<4>[   15.449656] bcmgenet fd580000.ethernet: failed to get enet-eee clock"}
- {"dt": "2024-07-11T08:37:33.203409", "lvl": "target", "msg": "<6>[   15.450242] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]"}
- {"dt": "2024-07-11T08:37:33.257024", "lvl": "target", "msg": "<6>[   15.466578] iproc-rng200 fe104000.rng: hwrng registered"}
- {"dt": "2024-07-11T08:37:33.259471", "lvl": "target", "msg": "<6>[   15.467955] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x0603ffffff -> 0x00f8000000"}
- {"dt": "2024-07-11T08:37:33.260536", "lvl": "target", "msg": "<6>[   15.483771] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0000000000"}
- {"dt": "2024-07-11T08:37:33.261483", "lvl": "target", "msg": "<6>[   15.496090] raspberrypi-clk raspberrypi-clk: CPU frequency range: min 600000000, max 1500000000"}
- {"dt": "2024-07-11T08:37:33.262614", "lvl": "target", "msg": "<5>[   15.514079] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-07-11T08:37:33.311011", "lvl": "target", "msg": "<6>[   15.542880] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)"}
- {"dt": "2024-07-11T08:37:33.312579", "lvl": "target", "msg": "<6>[   15.549300] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-07-11T08:37:33.313951", "lvl": "target", "msg": "<6>[   15.556195] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-07-11T08:37:33.315305", "lvl": "target", "msg": "<6>[   15.562146] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])"}
- {"dt": "2024-07-11T08:37:33.316751", "lvl": "target", "msg": "<6>[   15.573097] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400"}
- {"dt": "2024-07-11T08:37:33.318134", "lvl": "target", "msg": "<6>[   15.579338] Bluetooth: Core ver 2.22"}
- {"dt": "2024-07-11T08:37:33.353118", "lvl": "target", "msg": "<6>[   15.579713] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-07-11T08:37:33.355283", "lvl": "target", "msg": "<5>[   15.580934] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-07-11T08:37:33.356196", "lvl": "target", "msg": "<4>[   15.581036] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-07-11T08:37:33.357136", "lvl": "target", "msg": "<4>[   15.581044] platform regulatory.0: Falling back to sysfs fallback for: regulatory.db"}
- {"dt": "2024-07-11T08:37:33.358020", "lvl": "target", "msg": "<6>[   15.583683] NET: Registered protocol family 31"}
- {"dt": "2024-07-11T08:37:33.358887", "lvl": "target", "msg": "<6>[   15.591590] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-07-11T08:37:33.397508", "lvl": "target", "msg": "<6>[   15.609897] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-07-11T08:37:33.401579", "lvl": "target", "msg": "<6>[   15.614024] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330"}
- {"dt": "2024-07-11T08:37:33.403378", "lvl": "target", "msg": "<6>[   15.618490] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-07-11T08:37:33.404888", "lvl": "target", "msg": "<6>[   15.626690] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]"}
- {"dt": "2024-07-11T08:37:33.406157", "lvl": "target", "msg": "<6>[   15.634504] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-07-11T08:37:33.407609", "lvl": "target", "msg": "<6>[   15.639939] pci 0000:01:00.0: PME# supported from D0 D3cold"}
- {"dt": "2024-07-11T08:37:33.408424", "lvl": "target", "msg": "<6>[   15.641007] libphy: bcmgenet MII bus: probed"}
- {"dt": "2024-07-11T08:37:33.409083", "lvl": "target", "msg": "<6>[   15.645990] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-07-11T08:37:33.440724", "lvl": "target", "msg": "<6>[   15.653201] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-07-11T08:37:33.444938", "lvl": "target", "msg": "<6>[   15.658845] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-07-11T08:37:33.446655", "lvl": "target", "msg": "<6>[   15.663570] pci 0000:00:00.0: BAR 14: assigned [mem 0x600000000-0x6000fffff]"}
- {"dt": "2024-07-11T08:37:33.448245", "lvl": "target", "msg": "<4>[   15.668378] brcmfmac mmc0:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.bin failed with error -2"}
- {"dt": "2024-07-11T08:37:33.449922", "lvl": "target", "msg": "<6>[   15.673371] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]"}
- {"dt": "2024-07-11T08:37:33.484638", "lvl": "target", "msg": "<6>[   15.674426] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus"}
- {"dt": "2024-07-11T08:37:33.486595", "lvl": "target", "msg": "<4>[   15.680360] brcmfmac mmc0:0001:1: Falling back to sysfs fallback for: brcm/brcmfmac43455-sdio.bin"}
- {"dt": "2024-07-11T08:37:33.487653", "lvl": "target", "msg": "<6>[   15.689369] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-07-11T08:37:33.488646", "lvl": "target", "msg": "<6>[   15.697117] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-07-11T08:37:33.489592", "lvl": "target", "msg": "<6>[   15.706936] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]"}
- {"dt": "2024-07-11T08:37:33.490556", "lvl": "target", "msg": "<6>[   15.707174] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-07-11T08:37:33.491706", "lvl": "target", "msg": "<6>[   15.714836] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-07-11T08:37:33.528517", "lvl": "target", "msg": "<6>[   15.721560] pcieport 0000:00:00.0: PME: Signaling with IRQ 40"}
- {"dt": "2024-07-11T08:37:33.531571", "lvl": "target", "msg": "<6>[   15.730636] Bluetooth: HCI UART protocol LL registered"}
- {"dt": "2024-07-11T08:37:33.532919", "lvl": "target", "msg": "<6>[   15.736231] pcieport 0000:00:00.0: AER: enabled with IRQ 40"}
- {"dt": "2024-07-11T08:37:33.534134", "lvl": "target", "msg": "<6>[   15.740915] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-07-11T08:37:33.535353", "lvl": "target", "msg": "<6>[   15.748272] pci 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-07-11T08:37:33.536656", "lvl": "target", "msg": "<6>[   15.754569] Bluetooth: HCI UART protocol QCA registered"}
- {"dt": "2024-07-11T08:37:33.537874", "lvl": "target", "msg": "<6>[   15.760031] pci 0000:01:00.0: quirk_usb_early_handoff+0x0/0x850 took 11466 usecs"}
- {"dt": "2024-07-11T08:37:33.573490", "lvl": "target", "msg": "<4>[   15.760102] hci_uart_bcm serial0-0: serial0-0 supply vbat not found, using dummy regulator"}
- {"dt": "2024-07-11T08:37:33.575773", "lvl": "target", "msg": "<4>[   15.760169] hci_uart_bcm serial0-0: serial0-0 supply vddio not found, using dummy regulator"}
- {"dt": "2024-07-11T08:37:33.576793", "lvl": "target", "msg": "<6>[   15.820362] xhci_hcd 0000:01:00.0: xHCI Host Controller"}
- {"dt": "2024-07-11T08:37:33.577640", "lvl": "target", "msg": "<6>[   15.825950] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-07-11T08:37:33.578553", "lvl": "target", "msg": "<6>[   15.834070] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000000000000090"}
- {"dt": "2024-07-11T08:37:33.579652", "lvl": "target", "msg": "<6>[   15.844134] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-07-11T08:37:33.627780", "lvl": "target", "msg": "<6>[   15.848220] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-07-11T08:37:33.631855", "lvl": "target", "msg": "<6>[   15.852684] xhci_hcd 0000:01:00.0: xHCI Host Controller"}
- {"dt": "2024-07-11T08:37:33.633666", "lvl": "target", "msg": "<6>[   15.858259] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-07-11T08:37:33.635275", "lvl": "target", "msg": "<6>[   15.866037] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-07-11T08:37:33.636947", "lvl": "target", "msg": "<6>[   15.872688] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2024-07-11T08:37:33.638567", "lvl": "target", "msg": "<6>[   15.881427] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-07-11T08:37:33.640160", "lvl": "target", "msg": "<6>[   15.884856] uart-pl011 fe201000.serial: no DMA platform data"}
- {"dt": "2024-07-11T08:37:33.641138", "lvl": "target", "msg": "<6>[   15.885513] hub 2-0:1.0: 4 ports detected"}
- {"dt": "2024-07-11T08:37:33.768685", "lvl": "target", "msg": "done"}
- {"dt": "2024-07-11T08:37:33.769713", "lvl": "target", "msg": "Initializing random number generator... done."}
- {"dt": "2024-07-11T08:37:33.772377", "lvl": "target", "msg": "Starting network: OK"}
- {"dt": "2024-07-11T08:37:33.820541", "lvl": "target", "msg": "Starting dropbear sshd: <6>[   16.067111] NET: Registered protocol family 10"}
- {"dt": "2024-07-11T08:37:33.824576", "lvl": "target", "msg": "<6>[   16.072501] Segment Routing with IPv6"}
- {"dt": "2024-07-11T08:37:33.826306", "lvl": "target", "msg": "OK"}
- {"dt": "2024-07-11T08:37:33.827900", "lvl": "target", "msg": "/bin/sh: can't access tty; job control turned off"}
- {"dt": "2024-07-11T08:37:33.831935", "lvl": "debug", "msg": "Matched prompt #10: / #"}
- {"dt": "2024-07-11T08:37:33.833123", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "14.64", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:37:33.834074", "lvl": "debug", "msg": "Setting prompt string to ['/ #']"}
- {"dt": "2024-07-11T08:37:33.834691", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:15) [common]"}
- {"dt": "2024-07-11T08:37:33.835180", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "14.65", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:37:33.836184", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:17) [common]"}
- {"dt": "2024-07-11T08:37:33.836894", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:58) [common]"}
- {"dt": "2024-07-11T08:37:33.837585", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:01:02) [common]"}
- {"dt": "2024-07-11T08:37:33.838245", "lvl": "info", "msg": "start: 3 finalize (timeout 00:08:41) [common]"}
- {"dt": "2024-07-11T08:37:33.838914", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-07-11T08:37:33.839762", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '7'"}
- {"dt": "2024-07-11T08:37:37.262186", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-07-11T08:37:37.303490", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2024-07-11T08:37:37.407449", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2024-07-11T08:37:37.409520", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.57", "extra": {"status": "success"}, "level": "3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-07-11T08:37:37.413575", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:08:37) [common]"}
- {"dt": "2024-07-11T08:37:37.417264", "lvl": "feedback", "msg": "/ # <6>[   16.133256] Bluetooth: hci0: BCM: chip id 107", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.417916", "lvl": "feedback", "msg": "<6>[   16.138192] Bluetooth: hci0: BCM: features 0x2f", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.418453", "lvl": "feedback", "msg": "<6>[   16.144520] Bluetooth: hci0: BCM4345C0", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.418950", "lvl": "feedback", "msg": "<6>[   16.148590] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.419446", "lvl": "feedback", "msg": "<4>[   16.154902] bluetooth hci0: Direct firmware load for brcm/BCM4345C0.hcd failed with error -2", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.419954", "lvl": "feedback", "msg": "<4>[   16.163734] bluetooth hci0: Falling back to sysfs fallback for: brcm/BCM4345C0.hcd", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.420473", "lvl": "feedback", "msg": "<6>[   16.188736] usb 1-1: new high-speed USB device number 2 using xhci_hcd", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.420961", "lvl": "feedback", "msg": "<6>[   16.342776] hub 1-1:1.0: USB hub found", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.421434", "lvl": "feedback", "msg": "<6>[   16.347029] hub 1-1:1.0: 4 ports detected", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.421902", "lvl": "feedback", "msg": "<6>[   16.644734] usb 1-1.3: new high-speed USB device number 3 using xhci_hcd", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.422364", "lvl": "feedback", "msg": "<6>[   16.751055] hub 1-1.3:1.0: USB hub found", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.422822", "lvl": "feedback", "msg": "<6>[   16.755503] hub 1-1.3:1.0: 4 ports detected", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.423296", "lvl": "feedback", "msg": "<6>[   17.064734] usb 1-1.3.1: new high-speed USB device number 4 using xhci_hcd", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.423770", "lvl": "feedback", "msg": "<6>[   17.187200] hub 1-1.3.1:1.0: USB hub found", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.424240", "lvl": "feedback", "msg": "<6>[   17.191776] hub 1-1.3.1:1.0: 4 ports detected", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.424761", "lvl": "feedback", "msg": "<3>[   18.321046] usb 1-1.3.1-port1: Cannot enable. Maybe the USB cable is bad?", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.425240", "lvl": "feedback", "msg": "<3>[   19.241031] usb 1-1.3.1-port1: Cannot enable. Maybe the USB cable is bad?", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.425708", "lvl": "feedback", "msg": "<6>[   19.248344] usb 1-1.3.1-port1: attempt power cycle", "ns": "common"}
- {"dt": "2024-07-11T08:37:37.426490", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T08:37:38.417313", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-07-11T08:37:38.419428", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-07-11T08:37:38.522531", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T08:37:38.524849", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:05) [common]"}
- {"dt": "2024-07-11T08:37:38.525573", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-07-11T08:37:38.526192", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/ramdisk"}
- {"dt": "2024-07-11T08:37:38.531930", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/kernel"}
- {"dt": "2024-07-11T08:37:38.541817", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/dtb"}
- {"dt": "2024-07-11T08:37:38.543088", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/35452/tftp-deploy-jnibuy3n/modules"}
- {"dt": "2024-07-11T08:37:38.554467", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/35452"}
- {"dt": "2024-07-11T08:37:38.746721", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/35452"}
- {"dt": "2024-07-11T08:37:38.747623", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-07-11T08:37:38.748091", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
