- {"dt": "2024-11-25T15:06:12.198101", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.198506", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.198643", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.198783", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.198896", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.199034", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.199143", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.199259", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.199384", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-11-25T15:06:12.213594", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-11-25T15:06:12.213727", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-11-25T15:06:12.213870", "lvl": "info", "msg": "Start time: 2024-11-25 15:06:12.213861+00:00 (UTC)"}
- {"dt": "2024-11-25T15:06:12.214066", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/initramfs-netboot-image-raspberrypi5-20241028040434.ext4.gz exists"}
- {"dt": "2024-11-25T15:06:12.495790", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-11-25T15:06:12.699707", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/bcm2712-rpi-5-b.dtb exists"}
- {"dt": "2024-11-25T15:06:12.903456", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4.xz exists"}
- {"dt": "2024-11-25T15:06:13.111182", "lvl": "info", "msg": "validate duration: 0.90"}
- {"dt": "2024-11-25T15:06:13.111324", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-11-25T15:06:13.111504", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-11-25T15:06:13.111593", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-11-25T15:06:13.111670", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-11-25T15:06:13.111746", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-11-25T15:06:13.111934", "lvl": "debug", "msg": "Set_port 61974"}
- {"dt": "2024-11-25T15:06:13.112072", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-11-25T15:06:13.112184", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-11-25T15:06:13.112462", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/initramfs-netboot-image-raspberrypi5-20241028040434.ext4.gz"}
- {"dt": "2024-11-25T15:06:13.112586", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-11-25T15:06:13.112692", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.112798", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-11-25T15:06:13.416282", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.612475", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.712515", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.728961", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.814028", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.834875", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.835766", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.836490", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.917250", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.920335", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.929386", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.931133", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.940786", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.941360", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:13.946073", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.011193", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.032629", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.033738", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.066403", "lvl": "info", "msg": "2\u202FMB downloaded in 0.95\u202Fs (2.15\u202FMB/s)"}
- {"dt": "2024-11-25T15:06:14.066721", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:06:14.066824", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.95", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:06:14.067045", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:06:14.067157", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-11-25T15:06:14.067261", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-11-25T15:06:14.067494", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-11-25T15:06:14.067569", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/kernel/Image"}
- {"dt": "2024-11-25T15:06:14.067643", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.067721", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-25T15:06:14.369642", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.879529", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-11-25T15:06:14.992420", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.104462", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.240395", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.282740", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.374670", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.442956", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.490676", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.672459", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.683117", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.773100", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.798778", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-11-25T15:06:15.907633", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-11-25T15:06:16.959313", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-11-25T15:06:16.966159", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.057083", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.186562", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.193406", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.401659", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.533423", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.533672", "lvl": "info", "msg": "27\u202FMB downloaded in 3.47\u202Fs (7.95\u202FMB/s)"}
- {"dt": "2024-11-25T15:06:17.533860", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-11-25T15:06:17.533940", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.47", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:06:17.534110", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-11-25T15:06:17.534202", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:56) [common]"}
- {"dt": "2024-11-25T15:06:17.534290", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:56) [common]"}
- {"dt": "2024-11-25T15:06:17.534480", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-11-25T15:06:17.534554", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-11-25T15:06:17.534617", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.534680", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-25T15:06:17.832814", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.930256", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.931761", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.932576", "lvl": "info", "msg": "0\u202FMB downloaded in 0.40\u202Fs (0.19\u202FMB/s)"}
- {"dt": "2024-11-25T15:06:17.933236", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:06:17.933571", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.40", "extra": {"label": "dtb", "md5sum": "e8c29afbbe377e6f0331d46da1c32f91", "sha256sum": "a91e4fa0dcc0ab08b873cfac3c0b7fc17c22ee715d941cbc915a44966c1e8c99", "sha512sum": "6e77dc7abf1cdc1c07a6f9e5f0168f52a9704b6d0142112e5b28cef3e166f8b93612a76b6341e5168e6c1e3d7ab7b4ed115d87749a7379c2d5174a78e19b46c3", "size": ! "78059"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:06:17.934284", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:06:17.934646", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:55) [common]"}
- {"dt": "2024-11-25T15:06:17.935011", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:55) [common]"}
- {"dt": "2024-11-25T15:06:17.935669", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4.xz"}
- {"dt": "2024-11-25T15:06:17.935747", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-11-25T15:06:17.935826", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-11-25T15:06:17.935915", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-11-25T15:06:18.241405", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-25T15:06:20.632198", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-11-25T15:06:22.572776", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-11-25T15:06:24.490799", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-11-25T15:06:26.238988", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-11-25T15:06:27.975077", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-11-25T15:06:32.552264", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-11-25T15:06:34.419597", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-11-25T15:06:36.342398", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-11-25T15:06:37.903457", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-11-25T15:06:39.580593", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-11-25T15:06:41.250727", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-11-25T15:06:43.075404", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-11-25T15:06:44.870199", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-11-25T15:06:46.758275", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-11-25T15:06:48.899194", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-11-25T15:06:50.645518", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-11-25T15:06:52.242467", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-11-25T15:06:54.027411", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-11-25T15:06:55.790118", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-11-25T15:06:59.806058", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-11-25T15:07:01.224792", "lvl": "info", "msg": "470\u202FMB downloaded in 43.29\u202Fs (10.88\u202FMB/s)"}
- {"dt": "2024-11-25T15:07:01.225109", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:43) [common]"}
- {"dt": "2024-11-25T15:07:01.225224", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "43.29", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.225453", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:43) [common]"}
- {"dt": "2024-11-25T15:07:01.225570", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.225858", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy"}
- {"dt": "2024-11-25T15:07:01.226087", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin"}
- {"dt": "2024-11-25T15:07:01.226206", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/tests"}
- {"dt": "2024-11-25T15:07:01.226311", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/results"}
- {"dt": "2024-11-25T15:07:01.226417", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-add-keys"}
- {"dt": "2024-11-25T15:07:01.226589", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-add-sources"}
- {"dt": "2024-11-25T15:07:01.226743", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-background-process-start"}
- {"dt": "2024-11-25T15:07:01.226893", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-background-process-stop"}
- {"dt": "2024-11-25T15:07:01.227057", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-common-functions"}
- {"dt": "2024-11-25T15:07:01.227207", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-echo-ipv4"}
- {"dt": "2024-11-25T15:07:01.227351", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-install-packages"}
- {"dt": "2024-11-25T15:07:01.227493", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-installed-packages"}
- {"dt": "2024-11-25T15:07:01.227632", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-os-build"}
- {"dt": "2024-11-25T15:07:01.227777", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-probe-channel"}
- {"dt": "2024-11-25T15:07:01.227958", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-probe-ip"}
- {"dt": "2024-11-25T15:07:01.228107", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-target-ip"}
- {"dt": "2024-11-25T15:07:01.228249", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-target-mac"}
- {"dt": "2024-11-25T15:07:01.228396", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-target-storage"}
- {"dt": "2024-11-25T15:07:01.228546", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-case"}
- {"dt": "2024-11-25T15:07:01.228689", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-event"}
- {"dt": "2024-11-25T15:07:01.228830", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-feedback"}
- {"dt": "2024-11-25T15:07:01.228970", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-raise"}
- {"dt": "2024-11-25T15:07:01.229117", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-reference"}
- {"dt": "2024-11-25T15:07:01.229257", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-runner"}
- {"dt": "2024-11-25T15:07:01.229397", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-set"}
- {"dt": "2024-11-25T15:07:01.229537", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-test-shell"}
- {"dt": "2024-11-25T15:07:01.229682", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-install-packages (oe)"}
- {"dt": "2024-11-25T15:07:01.229882", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/bin/lava-installed-packages (oe)"}
- {"dt": "2024-11-25T15:07:01.230029", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/environment"}
- {"dt": "2024-11-25T15:07:01.230161", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-11-25T15:07:01.230235", "lvl": "debug", "msg": "- LAVA_JOB_ID=37784"}
- {"dt": "2024-11-25T15:07:01.230300", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-11-25T15:07:01.230418", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.230738", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.230835", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.230904", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-11-25T15:07:01.230980", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.231059", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.231118", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-11-25T15:07:01.231190", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.231267", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.231338", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-11-25T15:07:01.231425", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.231497", "lvl": "debug", "msg": "Using /lava-37784 at stage 0"}
- {"dt": "2024-11-25T15:07:01.231948", "lvl": "debug", "msg": "uuid=37784_1.5.4.1 testdef=None"}
- {"dt": "2024-11-25T15:07:01.232045", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.232127", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.232747", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.232825", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "37784_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.232982", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.233808", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.233895", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "skipped test-install-overlay": "37784_1.5.4.1", "uuid": "37784_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.234061", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.234845", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/0/tests/0_dmesg test_uuid 37784_1.5.4.1"}
- {"dt": "2024-11-25T15:07:01.235037", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.235114", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "37784_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.235278", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.235347", "lvl": "debug", "msg": "Using /lava-37784 at stage 1"}
- {"dt": "2024-11-25T15:07:01.235712", "lvl": "debug", "msg": "uuid=37784_1.5.4.5 testdef=None"}
- {"dt": "2024-11-25T15:07:01.235802", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.235899", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.236460", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.236532", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "37784_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.236684", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.237452", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.237530", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "skipped test-install-overlay": "37784_1.5.4.5", "uuid": "37784_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.237692", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.238423", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/1/tests/1_bootrr test_uuid 37784_1.5.4.5"}
- {"dt": "2024-11-25T15:07:01.238609", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.238684", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "37784_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:01.238825", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-11-25T15:07:01.238886", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/0 for stage 0"}
- {"dt": "2024-11-25T15:07:01.238985", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-11-25T15:07:01.239073", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/37784/lava-overlay-khur6bhy/lava-37784/1 for stage 1"}
- {"dt": "2024-11-25T15:07:01.239173", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-11-25T15:07:01.239274", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.239357", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.248789", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.248927", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.249026", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.249121", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.249215", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.249307", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.249406", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.249491", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.249577", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.249647", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-11-25T15:07:01.249717", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61974, NBD-ROOT: 37784/nbd-deploy-hgnn6_6r/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-11-25T15:07:01.250160", "lvl": "debug", "msg": "nbd-server 61974 /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-11-25T15:07:01.252860", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-11-25T15:07:01.252999", "lvl": "debug", "msg": "output: ** (process:6153): WARNING **: 15:07:01.252: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-11-25T15:07:01.253075", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-11-25T15:07:01.253139", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-11-25T15:07:01.253235", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.253321", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:00:48) [common]"}
- {"dt": "2024-11-25T15:07:01.253406", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.253490", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.253556", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63007'"}
- {"dt": "2024-11-25T15:07:01.257101", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-11-25T15:07:01.257685", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:07:01.257905", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.258047", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:09:12) [common]"}
- {"dt": "2024-11-25T15:07:01.258260", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'reset' '2'"}
- {"dt": "2024-11-25T15:07:02.190928", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-11-25T15:07:02.191539", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-11-25T15:07:06.222211", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-11-25T15:07:06.222887", "lvl": "debug", "msg": ">> Switched outlet 2 on\r"}
- {"dt": "2024-11-25T15:07:08.225599", "lvl": "debug", "msg": ">> export LANG='C'\r"}
- {"dt": "2024-11-25T15:07:08.226206", "lvl": "debug", "msg": ">> export LC_ALL='C.UTF-8'\r"}
- {"dt": "2024-11-25T15:07:08.226595", "lvl": "debug", "msg": ">> export PATH='/usr/local/bin:/usr/local/sbin:/bin:/usr/bin:/usr/sbin:/sbin'\r"}
- {"dt": "2024-11-25T15:07:08.226928", "lvl": "debug", "msg": ">> export PWD='/'\r"}
- {"dt": "2024-11-25T15:07:08.230842", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-11-25T15:07:08.234737", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp\r"}
- {"dt": "2024-11-25T15:07:08.242047", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-11-25T15:07:08.242582", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-11-25T15:07:08.242983", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-11-25T15:07:08.247244", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-11-25T15:07:08.251812", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.254901", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.258481", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4' and '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4' are the same file\r"}
- {"dt": "2024-11-25T15:07:08.300002", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz' and '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz' are the same file\r"}
- {"dt": "2024-11-25T15:07:08.327117", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61974      0.0.0.0:*    users:((\"nbd-server\",pid=6154,fd=3))\r"}
- {"dt": "2024-11-25T15:07:08.329021", "lvl": "debug", "msg": ">> DEBUG: PID=6154\r"}
- {"dt": "2024-11-25T15:07:08.336758", "lvl": "debug", "msg": ">> NBDPORT=61974\r"}
- {"dt": "2024-11-25T15:07:08.338906", "lvl": "debug", "msg": ">> basename: extra operand \u2018/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\u2019\r"}
- {"dt": "2024-11-25T15:07:08.339098", "lvl": "debug", "msg": ">> Try 'basename --help' for more information.\r"}
- {"dt": "2024-11-25T15:07:08.340287", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-11-25T15:07:08.340445", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-11-25T15:07:08.340561", "lvl": "debug", "msg": ">> \t\tkernel 37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.340663", "lvl": "debug", "msg": ">> \t\tinitramfs \r"}
- {"dt": "2024-11-25T15:07:08.340762", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-11-25T15:07:08.341795", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb' and '/var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb' are the same file\r"}
- {"dt": "2024-11-25T15:07:08.342196", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/dtb/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-11-25T15:07:08.344311", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r\r"}
- {"dt": "2024-11-25T15:07:08.346319", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-11-25T15:07:08.349000", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=37784/nbd-deploy-hgnn6_6r/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-11-25T15:07:08.351516", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.354901", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.417020", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61974      0.0.0.0:*    users:((\"nbd-server\",pid=6154,fd=3))\r"}
- {"dt": "2024-11-25T15:07:08.418849", "lvl": "debug", "msg": ">> DEBUG: PID=6154\r"}
- {"dt": "2024-11-25T15:07:08.425101", "lvl": "debug", "msg": ">> NBDPORT=61974\r"}
- {"dt": "2024-11-25T15:07:08.427281", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-11-25T15:07:08.427429", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-11-25T15:07:08.427530", "lvl": "debug", "msg": ">> \t\tkernel 37784/nbd-deploy-hgnn6_6r/kernel/Image\r"}
- {"dt": "2024-11-25T15:07:08.427620", "lvl": "debug", "msg": ">> \t\tinitramfs initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-11-25T15:07:08.427708", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-11-25T15:07:08.428839", "lvl": "debug", "msg": "Returned 0 in 7 seconds"}
- {"dt": "2024-11-25T15:07:08.529645", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:07) [common]"}
- {"dt": "2024-11-25T15:07:08.530216", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "7.27", "extra": {"status": "success"}, "level": "2.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:07:08.530876", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:07) [common]"}
- {"dt": "2024-11-25T15:07:08.531280", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:09:05) [common]"}
- {"dt": "2024-11-25T15:07:08.531627", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-11-25T15:07:08.531995", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-11-25T15:07:08.533006", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-11-25T15:07:08.533313", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-11-25T15:07:08.533572", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-11-25T15:07:08.533667", "lvl": "target", "msg": "\0"}
- {"dt": "2024-11-25T15:07:08.533725", "lvl": "target", "msg": "RPi: BOOTSYS release VERSION:5f2f71fa DATE: 2023/09/25 TIME: 11:44:03"}
- {"dt": "2024-11-25T15:07:08.533783", "lvl": "target", "msg": "BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1695638643 serial a18d1bdb boardrev d04170 stc 1605770"}
- {"dt": "2024-11-25T15:07:08.533840", "lvl": "target", "msg": "AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-11-25T15:07:08.533897", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-11-25T15:07:08.533953", "lvl": "target", "msg": "PM_RSTS: 0x00001000"}
- {"dt": "2024-11-25T15:07:08.534008", "lvl": "target", "msg": "part 00000000 reset_info 00000000"}
- {"dt": "2024-11-25T15:07:08.534063", "lvl": "target", "msg": "PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0"}
- {"dt": "2024-11-25T15:07:08.534118", "lvl": "target", "msg": "uSD voltage 3.3V"}
- {"dt": "2024-11-25T15:07:08.534173", "lvl": "target", "msg": "Initialising SDRAM 'Micron' 32Gb x2 total-size: 64 Gbit 4267"}
- {"dt": "2024-11-25T15:07:08.534229", "lvl": "target", "msg": "DDR 4267 1 0 64 152"}
- {"dt": "2024-11-25T15:07:10.202268", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-11-25T15:07:10.681142", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:10.776979", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-11-25T15:07:10.777550", "lvl": "target", "msg": "RP1_BOOT: fw size 25968"}
- {"dt": "2024-11-25T15:07:11.128400", "lvl": "target", "msg": "PCI2 init"}
- {"dt": "2024-11-25T15:07:11.128973", "lvl": "target", "msg": "PCI2 reset"}
- {"dt": "2024-11-25T15:07:11.200039", "lvl": "target", "msg": "PCIe scan 00001de4:00000001"}
- {"dt": "2024-11-25T15:07:11.200615", "lvl": "target", "msg": "RP1_CHIP_INFO 20001927"}
- {"dt": "2024-11-25T15:07:11.200919", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:11.201190", "lvl": "target", "msg": "RPi: BOOTLOADER release VERSION:5f2f71fa DATE: 2023/09/25 TIME: 11:44:03"}
- {"dt": "2024-11-25T15:07:11.201448", "lvl": "target", "msg": "BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1695638643 serial a18d1bdb boardrev d04170 stc 4578860"}
- {"dt": "2024-11-25T15:07:11.203533", "lvl": "target", "msg": "AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-11-25T15:07:11.245857", "lvl": "target", "msg": " status"}
- {"dt": "2024-11-25T15:07:11.246419", "lvl": "target", "msg": "USB_PD CONFIG 0 41"}
- {"dt": "2024-11-25T15:07:11.246721", "lvl": "target", "msg": "Boot mode: SD (01) order 24"}
- {"dt": "2024-11-25T15:07:11.246989", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.249083", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.344757", "lvl": "target", "msg": "EMMC"}
- {"dt": "2024-11-25T15:07:11.440579", "lvl": "target", "msg": "SD retry 1 oc 0"}
- {"dt": "2024-11-25T15:07:11.470759", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.566336", "lvl": "target", "msg": "SD retry 2 oc 0"}
- {"dt": "2024-11-25T15:07:11.593469", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.688954", "lvl": "target", "msg": "SDV1"}
- {"dt": "2024-11-25T15:07:11.784809", "lvl": "target", "msg": "SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-11-25T15:07:11.824659", "lvl": "target", "msg": "USB-PD: src-cap PDO object1 0x0a11912c"}
- {"dt": "2024-11-25T15:07:11.825216", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-11-25T15:07:11.825519", "lvl": "target", "msg": "Voltage 5000 mV"}
- {"dt": "2024-11-25T15:07:11.825781", "lvl": "target", "msg": "USB-PD: src-cap PDO object2 0x0012d12c"}
- {"dt": "2024-11-25T15:07:11.826033", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-11-25T15:07:11.826275", "lvl": "target", "msg": "Voltage 9000 mV"}
- {"dt": "2024-11-25T15:07:11.826514", "lvl": "target", "msg": "USB-PD: src-cap PDO object3 0x0014b12c"}
- {"dt": "2024-11-25T15:07:11.826757", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-11-25T15:07:11.826997", "lvl": "target", "msg": "Voltage 15000 mV"}
- {"dt": "2024-11-25T15:07:11.827233", "lvl": "target", "msg": "USB-PD: src-cap PDO object4 0x00164145"}
- {"dt": "2024-11-25T15:07:11.827470", "lvl": "target", "msg": "Current 3250 mA"}
- {"dt": "2024-11-25T15:07:11.827718", "lvl": "target", "msg": "Voltage 20000 mV"}
- {"dt": "2024-11-25T15:07:11.828281", "lvl": "target", "msg": "Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-11-25T15:07:11.828574", "lvl": "target", "msg": "Retry SD 1"}
- {"dt": "2024-11-25T15:07:11.861483", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.864767", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:11.960460", "lvl": "target", "msg": "EMMC"}
- {"dt": "2024-11-25T15:07:12.056211", "lvl": "target", "msg": "SD retry 1 oc 0"}
- {"dt": "2024-11-25T15:07:12.086352", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:12.181856", "lvl": "target", "msg": "SD retry 2 oc 0"}
- {"dt": "2024-11-25T15:07:12.208897", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-11-25T15:07:12.304595", "lvl": "target", "msg": "SDV1"}
- {"dt": "2024-11-25T15:07:12.400186", "lvl": "target", "msg": "SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-11-25T15:07:12.418026", "lvl": "target", "msg": "Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-11-25T15:07:12.421217", "lvl": "target", "msg": "Boot mode: USB-MSD (04) order 2"}
- {"dt": "2024-11-25T15:07:12.469033", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-11-25T15:07:12.469561", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:12.469868", "lvl": "target", "msg": "USBSTS 1"}
- {"dt": "2024-11-25T15:07:12.470134", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:12.470389", "lvl": "target", "msg": "xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-11-25T15:07:12.470635", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-11-25T15:07:12.470877", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:12.471118", "lvl": "target", "msg": "USBSTS 1"}
- {"dt": "2024-11-25T15:07:12.471353", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:12.472226", "lvl": "target", "msg": "xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-11-25T15:07:37.490734", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-11-25T15:07:37.491301", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:37.491613", "lvl": "target", "msg": "USBSTS 0"}
- {"dt": "2024-11-25T15:07:37.491923", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-11-25T15:07:37.492197", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-11-25T15:07:37.492447", "lvl": "target", "msg": "USBSTS 0"}
- {"dt": "2024-11-25T15:07:37.494016", "lvl": "target", "msg": "USB MSD stopped. Timeout: 25 seconds"}
- {"dt": "2024-11-25T15:07:37.494451", "lvl": "target", "msg": "Boot mode: NETWORK (02) order 0"}
- {"dt": "2024-11-25T15:07:37.573738", "lvl": "target", "msg": "NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.68.1"}
- {"dt": "2024-11-25T15:07:40.511489", "lvl": "target", "msg": "LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-11-25T15:07:40.512065", "lvl": "target", "msg": "Link ready"}
- {"dt": "2024-11-25T15:07:40.559373", "lvl": "target", "msg": "RX: 0 IP: 0 IPV4: 0 MAC: 0 UDP: 0 UDP RECV: 0 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.582418", "lvl": "target", "msg": "DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-11-25T15:07:48.582971", "lvl": "target", "msg": "YI_ADDR 192.168.68.231"}
- {"dt": "2024-11-25T15:07:48.583279", "lvl": "target", "msg": "SI_ADDR 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.583552", "lvl": "target", "msg": "OPTIONS:-"}
- {"dt": "2024-11-25T15:07:48.583810", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER"}
- {"dt": "2024-11-25T15:07:48.584099", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1"}
- {"dt": "2024-11-25T15:07:48.584353", "lvl": "target", "msg": "\top: 51 len:   4 "}
- {"dt": "2024-11-25T15:07:48.584595", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0"}
- {"dt": "2024-11-25T15:07:48.584835", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.585075", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-11-25T15:07:48.585312", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.585860", "lvl": "target", "msg": "NET 192.168.68.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.586150", "lvl": "target", "msg": "DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-11-25T15:07:48.586398", "lvl": "target", "msg": "YI_ADDR 192.168.68.231"}
- {"dt": "2024-11-25T15:07:48.586638", "lvl": "target", "msg": "SI_ADDR 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.586837", "lvl": "target", "msg": "OPTIONS:-"}
- {"dt": "2024-11-25T15:07:48.586893", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK"}
- {"dt": "2024-11-25T15:07:48.625693", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1"}
- {"dt": "2024-11-25T15:07:48.626258", "lvl": "target", "msg": "\top: 51 len:   4 "}
- {"dt": "2024-11-25T15:07:48.626568", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0"}
- {"dt": "2024-11-25T15:07:48.626839", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.627092", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1"}
- {"dt": "2024-11-25T15:07:48.627337", "lvl": "target", "msg": "\top: 150 len:   4 "}
- {"dt": "2024-11-25T15:07:48.627579", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-11-25T15:07:48.627819", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.628106", "lvl": "target", "msg": "\top: 15 len:  11 "}
- {"dt": "2024-11-25T15:07:48.628348", "lvl": "target", "msg": "ARP 192.168.68.1 70:70:fc:05:68:c7"}
- {"dt": "2024-11-25T15:07:48.628588", "lvl": "target", "msg": "NET 192.168.68.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.68.1"}
- {"dt": "2024-11-25T15:07:48.628841", "lvl": "target", "msg": "RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.629396", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 a18d1bdb/config.txt"}
- {"dt": "2024-11-25T15:07:48.629674", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.629919", "lvl": "target", "msg": "RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.668953", "lvl": "target", "msg": "TFTP 1: File not found "}
- {"dt": "2024-11-25T15:07:48.669532", "lvl": "target", "msg": "RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.669883", "lvl": "target", "msg": "BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-11-25T15:07:48.670175", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 config.txt"}
- {"dt": "2024-11-25T15:07:48.670444", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.670703", "lvl": "target", "msg": "RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.670956", "lvl": "target", "msg": "TFTP: complete 137"}
- {"dt": "2024-11-25T15:07:48.671208", "lvl": "target", "msg": "RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.671461", "lvl": "target", "msg": "Read config.txt bytes      137 hnd 0x0"}
- {"dt": "2024-11-25T15:07:48.672094", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 pieeprom.sig"}
- {"dt": "2024-11-25T15:07:48.672536", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.705682", "lvl": "target", "msg": "RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.706238", "lvl": "target", "msg": "TFTP 1: File not found "}
- {"dt": "2024-11-25T15:07:48.706543", "lvl": "target", "msg": "RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.706817", "lvl": "target", "msg": "Read pieeprom.upd failed"}
- {"dt": "2024-11-25T15:07:48.707070", "lvl": "target", "msg": "usb_max_current_enable default 0 max-current 3000"}
- {"dt": "2024-11-25T15:07:48.708798", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-11-25T15:07:48.709209", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:07:48.756465", "lvl": "target", "msg": "RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.828475", "lvl": "target", "msg": "TFTP: complete 78059"}
- {"dt": "2024-11-25T15:07:48.828993", "lvl": "target", "msg": "RX: 62 IP: 0 IPV4: 61 MAC: 61 UDP: 61 UDP RECV: 61 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-11-25T15:07:48.829299", "lvl": "target", "msg": "Read bcm2712-rpi-5-b.dtb bytes    78059 hnd 0x0"}
- {"dt": "2024-11-25T15:07:48.829573", "lvl": "target", "msg": "dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712"}
- {"dt": "2024-11-25T15:07:48.829828", "lvl": "target", "msg": "dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712"}
- {"dt": "2024-11-25T15:07:48.830076", "lvl": "target", "msg": "PM_RSTS 00001000"}
- {"dt": "2024-11-25T15:07:48.831598", "lvl": "target", "msg": "Selecting USB low current limit"}
- {"dt": "2024-11-25T15:07:48.832042", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:14.478429", "lvl": "target", "msg": "NOTICE:  BL31: v2.6(release):v2.6-239-g2a9ede0bd"}
- {"dt": "2024-11-25T15:08:14.481679", "lvl": "target", "msg": "NOTICE:  BL31: Built : 14:26:57, Jun 22 2023"}
- {"dt": "2024-11-25T15:08:14.675204", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]"}
- {"dt": "2024-11-25T15:08:14.675749", "lvl": "target", "msg": "[    0.000000] Linux version 6.6.22-v8-16k (oe-user@oe-host) (aarch64-agl-linux-gcc (GCC) 13.3.0, GNU ld (GNU Binutils) 2.42.0.20240716) #1 SMP PREEMPT Thu Oct 24 14:13:42 UTC 2024"}
- {"dt": "2024-11-25T15:08:14.676098", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-11-25T15:08:14.676888", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:07:58) [common]"}
- {"dt": "2024-11-25T15:08:14.677279", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2024-11-25T15:08:14.677576", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-11-25T15:08:14.677906", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-11-25T15:08:14.678219", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-11-25T15:08:14.678485", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-11-25T15:08:14.678753", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-11-25T15:08:14.678995", "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", "raspberrypi5:~#", "/ #", "login:", "Login incorrect"]}
- {"dt": "2024-11-25T15:08:14.679486", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:07:58)"}
- {"dt": "2024-11-25T15:08:14.681089", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-11-25T15:08:14.681176", "lvl": "target", "msg": "[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0"}
- {"dt": "2024-11-25T15:08:14.681240", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-11-25T15:08:14.718392", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000002000000, size 64 MiB"}
- {"dt": "2024-11-25T15:08:14.718934", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-11-25T15:08:14.719242", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000002000000..0x0000000005ffffff (65536 KiB) map reusable linux,cma"}
- {"dt": "2024-11-25T15:08:14.719877", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0"}
- {"dt": "2024-11-25T15:08:14.720166", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x000000003fd16180..0x000000003fd1627a (0 KiB) nomap non-reusable nvram@0"}
- {"dt": "2024-11-25T15:08:14.721811", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-11-25T15:08:14.761803", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]"}
- {"dt": "2024-11-25T15:08:14.762339", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-11-25T15:08:14.762643", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]"}
- {"dt": "2024-11-25T15:08:14.762914", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-11-25T15:08:14.763170", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-11-25T15:08:14.763415", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000007ffff]"}
- {"dt": "2024-11-25T15:08:14.763664", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]"}
- {"dt": "2024-11-25T15:08:14.763933", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x00000001ffffffff]"}
- {"dt": "2024-11-25T15:08:14.805100", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]"}
- {"dt": "2024-11-25T15:08:14.805631", "lvl": "target", "msg": "[    0.000000] On node 0, zone DMA: 512 pages in unavailable ranges"}
- {"dt": "2024-11-25T15:08:14.805940", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-11-25T15:08:14.806210", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-11-25T15:08:14.806463", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-11-25T15:08:14.806708", "lvl": "target", "msg": "[    0.000000] psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2024-11-25T15:08:14.806948", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.2"}
- {"dt": "2024-11-25T15:08:14.808276", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 14 pages/cpu s184936 r8192 d36248 u229376"}
- {"dt": "2024-11-25T15:08:14.848420", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-11-25T15:08:14.848950", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-11-25T15:08:14.849257", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Hardware dirty bit management"}
- {"dt": "2024-11-25T15:08:14.849523", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-11-25T15:08:14.849781", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-11-25T15:08:14.850029", "lvl": "target", "msg": "[    0.000000] CPU features: kernel page table isolation forced ON by KASLR"}
- {"dt": "2024-11-25T15:08:14.850275", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)"}
- {"dt": "2024-11-25T15:08:14.851566", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-11-25T15:08:14.894832", "lvl": "target", "msg": "[    0.000000] Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:E5:72:37 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000  console=ttyAMA10,115200n8 rw nbd.server=192.168.68.1 nbd.port=61974 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false rw"}
- {"dt": "2024-11-25T15:08:14.935074", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \\\"verbose earlyprintk\\\", will be passed to user space."}
- {"dt": "2024-11-25T15:08:14.935609", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, linear)"}
- {"dt": "2024-11-25T15:08:14.935953", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, linear)"}
- {"dt": "2024-11-25T15:08:14.936232", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 521728"}
- {"dt": "2024-11-25T15:08:14.936489", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-11-25T15:08:14.938220", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 4."}
- {"dt": "2024-11-25T15:08:14.978335", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000fbffc000-0x00000000ffffc000] (64MB)"}
- {"dt": "2024-11-25T15:08:14.978868", "lvl": "target", "msg": "[    0.000000] Memory: 8155696K/8380416K available (15104K kernel code, 2290K rwdata, 5024K rodata, 5568K init, 1247K bss, 159184K reserved, 65536K cma-reserved)"}
- {"dt": "2024-11-25T15:08:14.979180", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-11-25T15:08:14.979456", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 49047 entries in 48 pages"}
- {"dt": "2024-11-25T15:08:14.979711", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 48 pages with 2 groups"}
- {"dt": "2024-11-25T15:08:14.981485", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-11-25T15:08:15.021633", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-11-25T15:08:15.022163", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-11-25T15:08:15.022470", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-11-25T15:08:15.022743", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-11-25T15:08:15.023002", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-11-25T15:08:15.023246", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-11-25T15:08:15.023486", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-11-25T15:08:15.024788", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-11-25T15:08:15.064889", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-11-25T15:08:15.065430", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-11-25T15:08:15.065735", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-11-25T15:08:15.066007", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-11-25T15:08:15.066268", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-11-25T15:08:15.066522", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-11-25T15:08:15.108271", "lvl": "target", "msg": "[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-11-25T15:08:15.108805", "lvl": "target", "msg": "[    0.000167] Console: colour dummy device 80x25"}
- {"dt": "2024-11-25T15:08:15.109110", "lvl": "target", "msg": "[    0.000184] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-11-25T15:08:15.109385", "lvl": "target", "msg": "[    0.000188] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-11-25T15:08:15.109638", "lvl": "target", "msg": "[    0.000212] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-11-25T15:08:15.109885", "lvl": "target", "msg": "[    0.000276] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.111419", "lvl": "target", "msg": "[    0.000299] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.151569", "lvl": "target", "msg": "[    0.000639] cgroup: Disabling memory control group subsystem"}
- {"dt": "2024-11-25T15:08:15.152144", "lvl": "target", "msg": "[    0.001031] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-11-25T15:08:15.152453", "lvl": "target", "msg": "[    0.001052] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-11-25T15:08:15.152726", "lvl": "target", "msg": "[    0.001074] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-11-25T15:08:15.152985", "lvl": "target", "msg": "[    0.001129] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-11-25T15:08:15.153245", "lvl": "target", "msg": "[    0.001130] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-11-25T15:08:15.154703", "lvl": "target", "msg": "[    0.001698] EFI services will not be available."}
- {"dt": "2024-11-25T15:08:15.194845", "lvl": "target", "msg": "[    0.001756] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-11-25T15:08:15.195383", "lvl": "target", "msg": "[    0.001908] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-11-25T15:08:15.195690", "lvl": "target", "msg": "[    0.001949] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]"}
- {"dt": "2024-11-25T15:08:15.195999", "lvl": "target", "msg": "[    0.002117] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-11-25T15:08:15.196257", "lvl": "target", "msg": "[    0.002150] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]"}
- {"dt": "2024-11-25T15:08:15.196506", "lvl": "target", "msg": "[    0.002306] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-11-25T15:08:15.196750", "lvl": "target", "msg": "[    0.002337] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]"}
- {"dt": "2024-11-25T15:08:15.196992", "lvl": "target", "msg": "[    0.002366] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-11-25T15:08:15.238183", "lvl": "target", "msg": "[    0.002370] SMP: Total of 4 processors activated."}
- {"dt": "2024-11-25T15:08:15.238714", "lvl": "target", "msg": "[    0.002372] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-11-25T15:08:15.239021", "lvl": "target", "msg": "[    0.002374] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-11-25T15:08:15.239298", "lvl": "target", "msg": "[    0.002375] CPU features: detected: Common not Private translations"}
- {"dt": "2024-11-25T15:08:15.239558", "lvl": "target", "msg": "[    0.002377] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-11-25T15:08:15.239803", "lvl": "target", "msg": "[    0.002379] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-11-25T15:08:15.240084", "lvl": "target", "msg": "[    0.002380] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-11-25T15:08:15.241330", "lvl": "target", "msg": "[    0.002383] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-11-25T15:08:15.281456", "lvl": "target", "msg": "[    0.002384] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-11-25T15:08:15.282002", "lvl": "target", "msg": "[    0.002387] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-11-25T15:08:15.282312", "lvl": "target", "msg": "[    0.002418] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-11-25T15:08:15.282581", "lvl": "target", "msg": "[    0.002420] alternatives: applying system-wide alternatives"}
- {"dt": "2024-11-25T15:08:15.282834", "lvl": "target", "msg": "[    0.004266] devtmpfs: initialized"}
- {"dt": "2024-11-25T15:08:15.283079", "lvl": "target", "msg": "[    0.007051] Enabled cp15_barrier support"}
- {"dt": "2024-11-25T15:08:15.283323", "lvl": "target", "msg": "[    0.007057] Enabled setend support"}
- {"dt": "2024-11-25T15:08:15.284602", "lvl": "target", "msg": "[    0.007156] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-11-25T15:08:15.324796", "lvl": "target", "msg": "[    0.007161] futex hash table entries: 1024 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.325331", "lvl": "target", "msg": "[    0.007428] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-11-25T15:08:15.325639", "lvl": "target", "msg": "[    0.007547] DMI not present or invalid."}
- {"dt": "2024-11-25T15:08:15.325911", "lvl": "target", "msg": "[    0.007698] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-11-25T15:08:15.326167", "lvl": "target", "msg": "[    0.008258] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-11-25T15:08:15.326418", "lvl": "target", "msg": "[    0.008301] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-11-25T15:08:15.367957", "lvl": "target", "msg": "[    0.008347] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-11-25T15:08:15.368488", "lvl": "target", "msg": "[    0.008363] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-11-25T15:08:15.368796", "lvl": "target", "msg": "[    0.008410] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-11-25T15:08:15.369066", "lvl": "target", "msg": "[    0.008532] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-11-25T15:08:15.369324", "lvl": "target", "msg": "[    0.008543] cpuidle: using governor menu"}
- {"dt": "2024-11-25T15:08:15.369568", "lvl": "target", "msg": "[    0.008617] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-11-25T15:08:15.369809", "lvl": "target", "msg": "[    0.008646] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-11-25T15:08:15.371071", "lvl": "target", "msg": "[    0.008938] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-11-25T15:08:15.429592", "lvl": "target", "msg": "[    0.009705] bcm2835-mbox 107c013880.mailbox: mailbox enabled"}
- {"dt": "2024-11-25T15:08:15.430125", "lvl": "target", "msg": "[    0.009897] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-11-25T15:08:15.430439", "lvl": "target", "msg": "[    0.009906] printk: console [ttyAMA10] enabled"}
- {"dt": "2024-11-25T15:08:15.430712", "lvl": "target", "msg": "[    0.769971] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-09-25T10:44:03, variant start_cd"}
- {"dt": "2024-11-25T15:08:15.430970", "lvl": "target", "msg": "[    0.784023] raspberrypi-firmware soc:firmware: Firmware hash is 5f2f71fa00000000000000000000000000000000"}
- {"dt": "2024-11-25T15:08:15.432692", "lvl": "target", "msg": "[    0.799663] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-11-25T15:08:15.478249", "lvl": "target", "msg": "[    0.806742] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-11-25T15:08:15.478775", "lvl": "target", "msg": "[    0.806743] Modules: 129224 pages in range for PLT usage"}
- {"dt": "2024-11-25T15:08:15.479078", "lvl": "target", "msg": "[    0.816576] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1"}
- {"dt": "2024-11-25T15:08:15.479352", "lvl": "target", "msg": "[    0.829552] iommu: Default domain type: Translated"}
- {"dt": "2024-11-25T15:08:15.479606", "lvl": "target", "msg": "[    0.834398] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-11-25T15:08:15.479884", "lvl": "target", "msg": "[    0.840696] SCSI subsystem initialized"}
- {"dt": "2024-11-25T15:08:15.480134", "lvl": "target", "msg": "[    0.844494] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-11-25T15:08:15.481415", "lvl": "target", "msg": "[    0.850007] usbcore: registered new interface driver hub"}
- {"dt": "2024-11-25T15:08:15.522214", "lvl": "target", "msg": "[    0.855346] usbcore: registered new device driver usb"}
- {"dt": "2024-11-25T15:08:15.522744", "lvl": "target", "msg": "[    0.860506] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-11-25T15:08:15.523047", "lvl": "target", "msg": "[    0.865489] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-11-25T15:08:15.523317", "lvl": "target", "msg": "[    0.874660] PTP clock support registered"}
- {"dt": "2024-11-25T15:08:15.523571", "lvl": "target", "msg": "[    0.878643] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-11-25T15:08:15.523818", "lvl": "target", "msg": "[    0.884990] vgaarb: loaded"}
- {"dt": "2024-11-25T15:08:15.524098", "lvl": "target", "msg": "[    0.887821] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-11-25T15:08:15.525380", "lvl": "target", "msg": "[    0.894053] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-11-25T15:08:15.573407", "lvl": "target", "msg": "[    0.898006] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)"}
- {"dt": "2024-11-25T15:08:15.573923", "lvl": "target", "msg": "[    0.905110] FS-Cache: Loaded"}
- {"dt": "2024-11-25T15:08:15.574206", "lvl": "target", "msg": "[    0.911835] CacheFiles: Loaded"}
- {"dt": "2024-11-25T15:08:15.574287", "lvl": "target", "msg": "[    0.916441] NET: Registered PF_INET protocol family"}
- {"dt": "2024-11-25T15:08:15.574363", "lvl": "target", "msg": "[    0.921488] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.574436", "lvl": "target", "msg": "[    0.931474] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.576680", "lvl": "target", "msg": "[    0.940079] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.619414", "lvl": "target", "msg": "[    0.947854] TCP established hash table entries: 65536 (order: 5, 524288 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.619982", "lvl": "target", "msg": "[    0.956070] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.620296", "lvl": "target", "msg": "[    0.965218] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-11-25T15:08:15.620568", "lvl": "target", "msg": "[    0.971924] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.620824", "lvl": "target", "msg": "[    0.979554] UDP hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.622567", "lvl": "target", "msg": "[    0.986465] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-11-25T15:08:15.662991", "lvl": "target", "msg": "[    0.993867] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-11-25T15:08:15.663523", "lvl": "target", "msg": "[    0.999701] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-11-25T15:08:15.663877", "lvl": "target", "msg": "[    1.005643] RPC: Registered udp transport module."}
- {"dt": "2024-11-25T15:08:15.664159", "lvl": "target", "msg": "[    1.010360] RPC: Registered tcp transport module."}
- {"dt": "2024-11-25T15:08:15.664417", "lvl": "target", "msg": "[    1.015077] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-11-25T15:08:15.664666", "lvl": "target", "msg": "[    1.020584] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-11-25T15:08:15.664910", "lvl": "target", "msg": "[    1.027050] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-11-25T15:08:15.665153", "lvl": "target", "msg": "[    1.031044] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-11-25T15:08:15.715849", "lvl": "target", "msg": "[    1.036504] rootfs image is not initramfs (invalid magic at start of compressed archive); looks like an initrd"}
- {"dt": "2024-11-25T15:08:15.716407", "lvl": "target", "msg": "[    1.036570] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-11-25T15:08:15.716711", "lvl": "target", "msg": "[    1.060873] Freeing initrd memory: 12288K"}
- {"dt": "2024-11-25T15:08:15.716979", "lvl": "target", "msg": "[    1.064913] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)"}
- {"dt": "2024-11-25T15:08:15.717236", "lvl": "target", "msg": "[    1.064957] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-11-25T15:08:15.719037", "lvl": "target", "msg": "[    1.077609] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-11-25T15:08:15.781794", "lvl": "target", "msg": "[    1.116163] Initialise system trusted keyrings"}
- {"dt": "2024-11-25T15:08:15.782342", "lvl": "target", "msg": "[    1.120694] workingset: timestamp_bits=46 max_order=19 bucket_order=0"}
- {"dt": "2024-11-25T15:08:15.782655", "lvl": "target", "msg": "[    1.127173] zbud: loaded"}
- {"dt": "2024-11-25T15:08:15.782926", "lvl": "target", "msg": "[    1.129891] NFS: Registering the id_resolver key type"}
- {"dt": "2024-11-25T15:08:15.783180", "lvl": "target", "msg": "[    1.134965] Key type id_resolver registered"}
- {"dt": "2024-11-25T15:08:15.783425", "lvl": "target", "msg": "[    1.139159] Key type id_legacy registered"}
- {"dt": "2024-11-25T15:08:15.783667", "lvl": "target", "msg": "[    1.143183] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-11-25T15:08:15.784974", "lvl": "target", "msg": "[    1.149908] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-11-25T15:08:15.825475", "lvl": "target", "msg": "[    1.157378] F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-11-25T15:08:15.826022", "lvl": "target", "msg": "[    1.162948] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-11-25T15:08:15.826327", "lvl": "target", "msg": "[    1.167756] Key type asymmetric registered"}
- {"dt": "2024-11-25T15:08:15.826593", "lvl": "target", "msg": "[    1.171863] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-11-25T15:08:15.826845", "lvl": "target", "msg": "[    1.176771] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-11-25T15:08:15.827095", "lvl": "target", "msg": "[    1.184224] io scheduler mq-deadline registered"}
- {"dt": "2024-11-25T15:08:15.827338", "lvl": "target", "msg": "[    1.188769] io scheduler kyber registered"}
- {"dt": "2024-11-25T15:08:15.827577", "lvl": "target", "msg": "[    1.192795] io scheduler bfq registered"}
- {"dt": "2024-11-25T15:08:15.870484", "lvl": "target", "msg": "[    1.196860] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27)"}
- {"dt": "2024-11-25T15:08:15.871014", "lvl": "target", "msg": "[    1.204611] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28)"}
- {"dt": "2024-11-25T15:08:15.871315", "lvl": "target", "msg": "[    1.212362] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29)"}
- {"dt": "2024-11-25T15:08:15.871583", "lvl": "target", "msg": "[    1.220115] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 30)"}
- {"dt": "2024-11-25T15:08:15.873622", "lvl": "target", "msg": "[    1.229836] bcm2708_fb soc:fb: FB found 1 display(s)"}
- {"dt": "2024-11-25T15:08:15.896461", "lvl": "target", "msg": "[    1.244778] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-11-25T15:08:15.899613", "lvl": "target", "msg": "[    1.254479] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1080"}
- {"dt": "2024-11-25T15:08:15.962109", "lvl": "target", "msg": "[    1.293464] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled"}
- {"dt": "2024-11-25T15:08:15.962637", "lvl": "target", "msg": "[    1.300155] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART"}
- {"dt": "2024-11-25T15:08:15.962947", "lvl": "target", "msg": "[    1.310770] serial serial0: tty port ttyS0 registered"}
- {"dt": "2024-11-25T15:08:15.963220", "lvl": "target", "msg": "[    1.316103] iproc-rng200 107d208000.rng: hwrng registered"}
- {"dt": "2024-11-25T15:08:15.963475", "lvl": "target", "msg": "[    1.321564] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)"}
- {"dt": "2024-11-25T15:08:15.965306", "lvl": "target", "msg": "[    1.330074] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe"}
- {"dt": "2024-11-25T15:08:16.013343", "lvl": "target", "msg": "[    1.339503] brd: module loaded"}
- {"dt": "2024-11-25T15:08:16.013869", "lvl": "target", "msg": "[    1.343924] loop: module loaded"}
- {"dt": "2024-11-25T15:08:16.014171", "lvl": "target", "msg": "[    1.352457] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-11-25T15:08:16.014438", "lvl": "target", "msg": "[    1.359893] Loading iSCSI transport class v2.0-870."}
- {"dt": "2024-11-25T15:08:16.014691", "lvl": "target", "msg": "[    1.365608] usbcore: registered new device driver r8152-cfgselector"}
- {"dt": "2024-11-25T15:08:16.014938", "lvl": "target", "msg": "[    1.371914] usbcore: registered new interface driver r8152"}
- {"dt": "2024-11-25T15:08:16.015178", "lvl": "target", "msg": "[    1.377423] usbcore: registered new interface driver lan78xx"}
- {"dt": "2024-11-25T15:08:16.016557", "lvl": "target", "msg": "[    1.383164] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)"}
- {"dt": "2024-11-25T15:08:16.064696", "lvl": "target", "msg": "[    1.389084] usbcore: registered new interface driver uas"}
- {"dt": "2024-11-25T15:08:16.065228", "lvl": "target", "msg": "[    1.394420] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-11-25T15:08:16.065532", "lvl": "target", "msg": "[    1.400477] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-11-25T15:08:16.065797", "lvl": "target", "msg": "[    1.407032] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-11-25T15:08:16.066048", "lvl": "target", "msg": "[    1.413062] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-11-25T15:08:16.066295", "lvl": "target", "msg": "[    1.418656] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-11-25T15:08:16.066534", "lvl": "target", "msg": "[    1.424680] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2024-11-25T15:08:16.112363", "lvl": "target", "msg": "[    1.438107] rpi-rtc soc:rpi_rtc: registered as rtc0"}
- {"dt": "2024-11-25T15:08:16.112889", "lvl": "target", "msg": "[    1.444478] rpi-rtc soc:rpi_rtc: setting system clock to 1970-01-01T00:01:09 UTC (69)"}
- {"dt": "2024-11-25T15:08:16.113223", "lvl": "target", "msg": "[    1.452390] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-11-25T15:08:16.113493", "lvl": "target", "msg": "[    1.458820] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!"}
- {"dt": "2024-11-25T15:08:16.113749", "lvl": "target", "msg": "[    1.465375] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-11-25T15:08:16.113998", "lvl": "target", "msg": "[    1.474195] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-11-25T15:08:16.114244", "lvl": "target", "msg": "[    1.480406] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-11-25T15:08:16.156375", "lvl": "target", "msg": "[    1.484794] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-11-25T15:08:16.156899", "lvl": "target", "msg": "[    1.490609] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-11-25T15:08:16.157208", "lvl": "target", "msg": "[    1.496682] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ...."}
- {"dt": "2024-11-25T15:08:16.157476", "lvl": "target", "msg": "[    1.503162] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2024-11-25T15:08:16.157732", "lvl": "target", "msg": "[    1.508348] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-11-25T15:08:16.157979", "lvl": "target", "msg": "[    1.513938] usbhid: USB HID core driver"}
- {"dt": "2024-11-25T15:08:16.158222", "lvl": "target", "msg": "[    1.518016] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available"}
- {"dt": "2024-11-25T15:08:16.159523", "lvl": "target", "msg": "[    1.526334] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-11-25T15:08:16.205570", "lvl": "target", "msg": "[    1.532654] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-11-25T15:08:16.206100", "lvl": "target", "msg": "[    1.537741] Key type dns_resolver registered"}
- {"dt": "2024-11-25T15:08:16.206401", "lvl": "target", "msg": "[    1.545411] registered taskstats version 1"}
- {"dt": "2024-11-25T15:08:16.206668", "lvl": "target", "msg": "[    1.549570] Loading compiled-in X.509 certificates"}
- {"dt": "2024-11-25T15:08:16.206924", "lvl": "target", "msg": "[    1.556121] Key type .fscrypt registered"}
- {"dt": "2024-11-25T15:08:16.207173", "lvl": "target", "msg": "[    1.560055] Key type fscrypt-provisioning registered"}
- {"dt": "2024-11-25T15:08:16.207417", "lvl": "target", "msg": "[    1.565680] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges:"}
- {"dt": "2024-11-25T15:08:16.208731", "lvl": "target", "msg": "[    1.572675] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@120000, using [bus 00-ff]"}
- {"dt": "2024-11-25T15:08:16.250319", "lvl": "target", "msg": "[    1.581795] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000"}
- {"dt": "2024-11-25T15:08:16.250848", "lvl": "target", "msg": "[    1.590099] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000"}
- {"dt": "2024-11-25T15:08:16.251151", "lvl": "target", "msg": "[    1.598434] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000"}
- {"dt": "2024-11-25T15:08:16.251421", "lvl": "target", "msg": "[    1.606732] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000"}
- {"dt": "2024-11-25T15:08:16.251673", "lvl": "target", "msg": "[    1.616203] brcm-pcie 1000120000.pcie: Forcing gen 2"}
- {"dt": "2024-11-25T15:08:16.253458", "lvl": "target", "msg": "[    1.621382] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-11-25T15:08:16.294249", "lvl": "target", "msg": "[    1.627847] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-11-25T15:08:16.294777", "lvl": "target", "msg": "[    1.633350] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])"}
- {"dt": "2024-11-25T15:08:16.295083", "lvl": "target", "msg": "[    1.643915] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])"}
- {"dt": "2024-11-25T15:08:16.295355", "lvl": "target", "msg": "[    1.655098] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400"}
- {"dt": "2024-11-25T15:08:16.295612", "lvl": "target", "msg": "[    1.661142] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-11-25T15:08:16.312738", "lvl": "target", "msg": "[    1.667222] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-11-25T15:08:16.458803", "lvl": "target", "msg": "[    1.779824] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)"}
- {"dt": "2024-11-25T15:08:16.459334", "lvl": "target", "msg": "[    1.786477] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000"}
- {"dt": "2024-11-25T15:08:16.459639", "lvl": "target", "msg": "[    1.792514] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff]"}
- {"dt": "2024-11-25T15:08:16.459938", "lvl": "target", "msg": "[    1.798806] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff]"}
- {"dt": "2024-11-25T15:08:16.460198", "lvl": "target", "msg": "[    1.805098] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff]"}
- {"dt": "2024-11-25T15:08:16.460444", "lvl": "target", "msg": "[    1.811446] pci 0000:01:00.0: supports D1"}
- {"dt": "2024-11-25T15:08:16.460686", "lvl": "target", "msg": "[    1.815464] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold"}
- {"dt": "2024-11-25T15:08:16.502080", "lvl": "target", "msg": "[    1.831828] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-11-25T15:08:16.502612", "lvl": "target", "msg": "[    1.838470] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-11-25T15:08:16.502920", "lvl": "target", "msg": "[    1.845633] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff]"}
- {"dt": "2024-11-25T15:08:16.503189", "lvl": "target", "msg": "[    1.852795] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff]"}
- {"dt": "2024-11-25T15:08:16.503441", "lvl": "target", "msg": "[    1.859957] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff]"}
- {"dt": "2024-11-25T15:08:16.503701", "lvl": "target", "msg": "[    1.867120] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-11-25T15:08:16.505231", "lvl": "target", "msg": "[    1.872099] pci 0000:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-11-25T15:08:16.545548", "lvl": "target", "msg": "[    1.879261] pci 0000:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512"}
- {"dt": "2024-11-25T15:08:16.546093", "lvl": "target", "msg": "[    1.887736] pci 0000:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512"}
- {"dt": "2024-11-25T15:08:16.546401", "lvl": "target", "msg": "[    1.896258] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-11-25T15:08:16.546673", "lvl": "target", "msg": "[    1.902398] pcieport 0000:00:00.0: PME: Signaling with IRQ 38"}
- {"dt": "2024-11-25T15:08:16.546929", "lvl": "target", "msg": "[    1.908200] pcieport 0000:00:00.0: AER: enabled with IRQ 38"}
- {"dt": "2024-11-25T15:08:16.548745", "lvl": "target", "msg": "[    1.913845] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200"}
- {"dt": "2024-11-25T15:08:16.578010", "lvl": "target", "msg": "[    1.922928] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200"}
- {"dt": "2024-11-25T15:08:16.578542", "lvl": "target", "msg": "[    1.932189] rp1 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-11-25T15:08:16.581107", "lvl": "target", "msg": "[    1.938645] rp1 0000:01:00.0: chip_id 0x20001927"}
- {"dt": "2024-11-25T15:08:16.634672", "lvl": "target", "msg": "[    1.965783] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 106 (d8:3a:dd:e5:72:37)"}
- {"dt": "2024-11-25T15:08:16.635199", "lvl": "target", "msg": "[    1.979096] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels"}
- {"dt": "2024-11-25T15:08:16.635502", "lvl": "target", "msg": "[    1.987612] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.635770", "lvl": "target", "msg": "[    1.992684] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-11-25T15:08:16.637950", "lvl": "target", "msg": "[    2.000364] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-11-25T15:08:16.678135", "lvl": "target", "msg": "[    2.009366] xhci-hcd xhci-hcd.0: irq 131, io mem 0x1f00200000"}
- {"dt": "2024-11-25T15:08:16.678659", "lvl": "target", "msg": "[    2.015192] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.678966", "lvl": "target", "msg": "[    2.020259] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-11-25T15:08:16.679239", "lvl": "target", "msg": "[    2.027508] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-11-25T15:08:16.679495", "lvl": "target", "msg": "[    2.033656] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-11-25T15:08:16.679747", "lvl": "target", "msg": "[    2.041953] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-11-25T15:08:16.681293", "lvl": "target", "msg": "[    2.049205] usb usb1: Product: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.721656", "lvl": "target", "msg": "[    2.054097] usb usb1: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-11-25T15:08:16.722197", "lvl": "target", "msg": "[    2.060123] usb usb1: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-11-25T15:08:16.722498", "lvl": "target", "msg": "[    2.064672] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-11-25T15:08:16.722768", "lvl": "target", "msg": "[    2.068441] hub 1-0:1.0: 2 ports detected"}
- {"dt": "2024-11-25T15:08:16.723023", "lvl": "target", "msg": "[    2.072559] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-11-25T15:08:16.723270", "lvl": "target", "msg": "[    2.080857] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-11-25T15:08:16.723517", "lvl": "target", "msg": "[    2.088107] usb usb2: Product: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.724853", "lvl": "target", "msg": "[    2.092998] usb usb2: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-11-25T15:08:16.766132", "lvl": "target", "msg": "[    2.099034] usb usb2: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-11-25T15:08:16.766655", "lvl": "target", "msg": "[    2.103620] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-11-25T15:08:16.766957", "lvl": "target", "msg": "[    2.107387] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-11-25T15:08:16.767225", "lvl": "target", "msg": "[    2.111546] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.767478", "lvl": "target", "msg": "[    2.116731] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-11-25T15:08:16.767727", "lvl": "target", "msg": "[    2.124413] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-11-25T15:08:16.768049", "lvl": "target", "msg": "[    2.133509] xhci-hcd xhci-hcd.1: irq 136, io mem 0x1f00300000"}
- {"dt": "2024-11-25T15:08:16.809734", "lvl": "target", "msg": "[    2.139332] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.810265", "lvl": "target", "msg": "[    2.144398] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-11-25T15:08:16.810570", "lvl": "target", "msg": "[    2.151745] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-11-25T15:08:16.810839", "lvl": "target", "msg": "[    2.157882] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-11-25T15:08:16.811094", "lvl": "target", "msg": "[    2.166273] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-11-25T15:08:16.811343", "lvl": "target", "msg": "[    2.173520] usb usb3: Product: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.812880", "lvl": "target", "msg": "[    2.178411] usb usb3: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-11-25T15:08:16.853527", "lvl": "target", "msg": "[    2.184531] usb usb3: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-11-25T15:08:16.854055", "lvl": "target", "msg": "[    2.189060] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-11-25T15:08:16.854357", "lvl": "target", "msg": "[    2.192827] hub 3-0:1.0: 2 ports detected"}
- {"dt": "2024-11-25T15:08:16.854625", "lvl": "target", "msg": "[    2.196936] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-11-25T15:08:16.854883", "lvl": "target", "msg": "[    2.205331] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-11-25T15:08:16.855130", "lvl": "target", "msg": "[    2.212602] usb usb4: Product: xHCI Host Controller"}
- {"dt": "2024-11-25T15:08:16.855374", "lvl": "target", "msg": "[    2.217493] usb usb4: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-11-25T15:08:16.856776", "lvl": "target", "msg": "[    2.223672] usb usb4: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-11-25T15:08:16.898205", "lvl": "target", "msg": "[    2.228204] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-11-25T15:08:16.898729", "lvl": "target", "msg": "[    2.231970] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-11-25T15:08:16.899034", "lvl": "target", "msg": "[    2.236570] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-11-25T15:08:16.899308", "lvl": "target", "msg": "[    2.244836] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.899566", "lvl": "target", "msg": "[    2.253069] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.899816", "lvl": "target", "msg": "[    2.261126] platform 1000800000.codec: Adding to iommu group 0"}
- {"dt": "2024-11-25T15:08:16.901370", "lvl": "target", "msg": "[    2.266983] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.942173", "lvl": "target", "msg": "[    2.275379] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.942687", "lvl": "target", "msg": "[    2.283601] platform 1000880000.pisp_be: Adding to iommu group 0"}
- {"dt": "2024-11-25T15:08:16.942993", "lvl": "target", "msg": "[    2.289828] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.943260", "lvl": "target", "msg": "[    2.297687] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:16.943515", "lvl": "target", "msg": "[    2.305908] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-11-25T15:08:16.997162", "lvl": "target", "msg": "[    2.312891] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-11-25T15:08:16.997694", "lvl": "target", "msg": "[    2.321337] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-11-25T15:08:16.998002", "lvl": "target", "msg": "[    2.328366] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-11-25T15:08:16.998275", "lvl": "target", "msg": "[    2.336814] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-11-25T15:08:16.998529", "lvl": "target", "msg": "[    2.343843] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO"}
- {"dt": "2024-11-25T15:08:16.998778", "lvl": "target", "msg": "[    2.349015] mmc0: CQHCI version 5.10"}
- {"dt": "2024-11-25T15:08:16.999023", "lvl": "target", "msg": "[    2.349140] mmc1: CQHCI version 5.10"}
- {"dt": "2024-11-25T15:08:16.999264", "lvl": "target", "msg": "[    2.352244] of_cfs_init"}
- {"dt": "2024-11-25T15:08:17.000265", "lvl": "target", "msg": "[    2.352282] of_cfs_init: OK"}
- {"dt": "2024-11-25T15:08:17.032423", "lvl": "target", "msg": "[    2.387826] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit"}
- {"dt": "2024-11-25T15:08:17.243578", "lvl": "target", "msg": "[    2.548364] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit"}
- {"dt": "2024-11-25T15:08:17.244151", "lvl": "target", "msg": "[    2.563215] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)"}
- {"dt": "2024-11-25T15:08:17.244469", "lvl": "target", "msg": "[    2.574140] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode"}
- {"dt": "2024-11-25T15:08:17.244743", "lvl": "target", "msg": "[    2.584359] pps pps0: new PPS source ptp0"}
- {"dt": "2024-11-25T15:08:17.245002", "lvl": "target", "msg": "[    2.588422] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered."}
- {"dt": "2024-11-25T15:08:17.246857", "lvl": "target", "msg": "[    2.603240] mmc1: new ultra high speed DDR50 SDIO card at address 0001"}
- {"dt": "2024-11-25T15:08:20.301219", "lvl": "target", "msg": "[    5.652958] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-11-25T15:08:20.365208", "lvl": "target", "msg": "[    5.683822] Sending DHCP requests ., OK"}
- {"dt": "2024-11-25T15:08:20.365748", "lvl": "target", "msg": "[    5.703674] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.68.231"}
- {"dt": "2024-11-25T15:08:20.366049", "lvl": "target", "msg": "[    5.711628] IP-Config: Complete:"}
- {"dt": "2024-11-25T15:08:20.366316", "lvl": "target", "msg": "[    5.714864]      device=eth0, hwaddr=d8:3a:dd:e5:72:37, ipaddr=192.168.68.231, mask=255.255.255.0, gw=192.168.68.1"}
- {"dt": "2024-11-25T15:08:20.367266", "lvl": "target", "msg": "[    5.725259]      host=192.168.68.231, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-11-25T15:08:20.368611", "lvl": "target", "msg": "[    5.732246]      bootserver=192.168.68.1, rootserver=192.168.68.1, rootpath="}
- {"dt": "2024-11-25T15:08:20.369010", "lvl": "target", "msg": "[    5.732248]      nameserver0=10.1.1.1"}
- {"dt": "2024-11-25T15:08:20.384035", "lvl": "target", "msg": "[    5.743103] clk: Disabling unused clocks"}
- {"dt": "2024-11-25T15:08:20.406643", "lvl": "target", "msg": "[    5.757243] ALSA device list:"}
- {"dt": "2024-11-25T15:08:20.407181", "lvl": "target", "msg": "[    5.760219]   No soundcards found."}
- {"dt": "2024-11-25T15:08:20.407475", "lvl": "target", "msg": "[    5.763672] uart-pl011 107d001000.serial: no DMA platform data"}
- {"dt": "2024-11-25T15:08:20.409844", "lvl": "target", "msg": "[    5.769543] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-11-25T15:08:27.514685", "lvl": "target", "msg": "[   12.883853] RAMDISK: ext2 filesystem found at block 0"}
- {"dt": "2024-11-25T15:08:27.656246", "lvl": "target", "msg": "[   12.888924] RAMDISK: Loading 12288KiB [1 disk] into ram disk... |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b"}
- {"dt": "2024-11-25T15:08:27.685140", "lvl": "target", "msg": "[   12.898805] |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\bdone."}
- {"dt": "2024-11-25T15:08:27.733288", "lvl": "target", "msg": "[   13.056152] EXT4-fs (ram0): mounted filesystem d291e3c8-a47c-4bba-b248-a917c9104949 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-11-25T15:08:27.733893", "lvl": "target", "msg": "[   13.067877] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-11-25T15:08:27.734235", "lvl": "target", "msg": "[   13.073753] devtmpfs: mounted"}
- {"dt": "2024-11-25T15:08:27.734508", "lvl": "target", "msg": "[   13.080292] Freeing unused kernel memory: 5568K"}
- {"dt": "2024-11-25T15:08:27.734782", "lvl": "target", "msg": "[   13.084883] Run /sbin/init as init process"}
- {"dt": "2024-11-25T15:08:27.735046", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-11-25T15:08:27.735303", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-11-25T15:08:27.776454", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-11-25T15:08:27.776970", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-11-25T15:08:27.777303", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-11-25T15:08:27.778340", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\[   13.128136] nbd0: detected capacity change from 0 to 8962048"}
- {"dt": "2024-11-25T15:08:27.778659", "lvl": "target", "msg": "___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-11-25T15:08:27.778922", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-11-25T15:08:27.779182", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2024-11-25T15:08:27.780168", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-11-25T15:08:27.829688", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-11-25T15:08:27.830201", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-11-25T15:08:27.830505", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-11-25T15:08:27.830776", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-11-25T15:08:27.831028", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-11-25T15:08:27.831272", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-11-25T15:08:27.831512", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-11-25T15:08:27.831747", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.68.1:61974"}
- {"dt": "2024-11-25T15:08:27.832748", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-11-25T15:08:27.833033", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-11-25T15:08:29.341226", "lvl": "target", "msg": "[   14.671321] EXT4-fs (nbd0): mounted filesystem 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-11-25T15:08:29.341798", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2024-11-25T15:08:29.342110", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2024-11-25T15:08:29.342385", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2024-11-25T15:08:29.342642", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2024-11-25T15:08:29.342894", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2024-11-25T15:08:29.344509", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2024-11-25T15:08:29.697348", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2024-11-25T15:08:29.700573", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0,enp2s0,enp3s0,end0,enp0s2,enp0s1 '"}
- {"dt": "2024-11-25T15:08:29.780229", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2024-11-25T15:08:29.860001", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2024-11-25T15:08:29.909819", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-11-25T15:08:29.910289", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2024-11-25T15:08:29.910604", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2024-11-25T15:08:29.910882", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2024-11-25T15:08:29.911845", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2024-11-25T15:08:29.912155", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2024-11-25T15:08:29.913294", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2024-11-25T15:08:30.748264", "lvl": "target", "msg": "[   16.102912] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2024-11-25T15:08:30.915788", "lvl": "target", "msg": "[   16.266401] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-11-25T15:08:30.916368", "lvl": "target", "msg": "[   16.271844] Segment Routing with IPv6"}
- {"dt": "2024-11-25T15:08:30.919039", "lvl": "target", "msg": "[   16.275519] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-11-25T15:08:31.062216", "lvl": "target", "msg": "[   16.391772] systemd[1]: systemd 255.4^ running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)"}
- {"dt": "2024-11-25T15:08:31.064785", "lvl": "target", "msg": "[   16.423781] systemd[1]: Detected architecture arm64."}
- {"dt": "2024-11-25T15:08:31.080619", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:31.081265", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 18.0.2 (ricefish)!"}
- {"dt": "2024-11-25T15:08:31.081614", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:31.096394", "lvl": "target", "msg": "[   16.463712] systemd[1]: Hostname set to <raspberrypi5>."}
- {"dt": "2024-11-25T15:08:31.138332", "lvl": "target", "msg": "[   16.493057] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2024-11-25T15:08:31.673173", "lvl": "target", "msg": "[   17.027554] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2024-11-25T15:08:31.715117", "lvl": "target", "msg": "[   17.072680] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2024-11-25T15:08:31.718177", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2024-11-25T15:08:31.796663", "lvl": "target", "msg": "[   17.092214] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2024-11-25T15:08:31.797171", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2024-11-25T15:08:31.797476", "lvl": "target", "msg": "[   17.112156] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2024-11-25T15:08:31.797749", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2024-11-25T15:08:31.798006", "lvl": "target", "msg": "[   17.132145] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2024-11-25T15:08:31.798960", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2024-11-25T15:08:31.799268", "lvl": "target", "msg": "[   17.152009] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2024-11-25T15:08:31.800276", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2024-11-25T15:08:31.862185", "lvl": "target", "msg": "[   17.171921] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-11-25T15:08:31.862707", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-11-25T15:08:31.863729", "lvl": "target", "msg": "[   17.195891] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-11-25T15:08:31.864065", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-11-25T15:08:31.864329", "lvl": "target", "msg": "[   17.215856] systemd[1]: Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-11-25T15:08:31.864581", "lvl": "target", "msg": "         Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-11-25T15:08:31.945705", "lvl": "target", "msg": "[   17.235880] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2024-11-25T15:08:31.946239", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2024-11-25T15:08:31.946547", "lvl": "target", "msg": "[   17.255842] systemd[1]: Reached target Path Units."}
- {"dt": "2024-11-25T15:08:31.946819", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2024-11-25T15:08:31.947076", "lvl": "target", "msg": "[   17.271856] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2024-11-25T15:08:31.947324", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2024-11-25T15:08:31.947564", "lvl": "target", "msg": "[   17.287846] systemd[1]: Reached target Slice Units."}
- {"dt": "2024-11-25T15:08:31.947804", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2024-11-25T15:08:31.948077", "lvl": "target", "msg": "[   17.303853] systemd[1]: Reached target Swaps."}
- {"dt": "2024-11-25T15:08:31.949053", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2024-11-25T15:08:32.020254", "lvl": "target", "msg": "[   17.323080] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2024-11-25T15:08:32.020776", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2024-11-25T15:08:32.021079", "lvl": "target", "msg": "[   17.339926] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-11-25T15:08:32.021348", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-11-25T15:08:32.021602", "lvl": "target", "msg": "[   17.360111] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2024-11-25T15:08:32.021854", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2024-11-25T15:08:32.022098", "lvl": "target", "msg": "[   17.375975] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-11-25T15:08:32.023441", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-11-25T15:08:32.093249", "lvl": "target", "msg": "[   17.395990] systemd[1]: Listening on Journal Socket."}
- {"dt": "2024-11-25T15:08:32.093776", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2024-11-25T15:08:32.094085", "lvl": "target", "msg": "[   17.412034] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2024-11-25T15:08:32.094360", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2024-11-25T15:08:32.094618", "lvl": "target", "msg": "[   17.432000] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2024-11-25T15:08:32.094866", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2024-11-25T15:08:32.095113", "lvl": "target", "msg": "[   17.447950] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2024-11-25T15:08:32.095358", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2024-11-25T15:08:32.096367", "lvl": "target", "msg": "[   17.463958] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2024-11-25T15:08:32.134005", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2024-11-25T15:08:32.137131", "lvl": "target", "msg": "[   17.483993] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages)."}
- {"dt": "2024-11-25T15:08:32.208686", "lvl": "target", "msg": "[   17.519941] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2024-11-25T15:08:32.209190", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2024-11-25T15:08:32.209494", "lvl": "target", "msg": "[   17.540714] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2024-11-25T15:08:32.209765", "lvl": "target", "msg": "         Mounting Kernel Debug Fi[   17.547329] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2024-11-25T15:08:32.210025", "lvl": "target", "msg": "le System..."}
- {"dt": "2024-11-25T15:08:32.210270", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2024-11-25T15:08:32.210511", "lvl": "target", "msg": "[   17.566410] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2024-11-25T15:08:32.264194", "lvl": "target", "msg": "         Mounting Temporary Directory /tm[   17.581311] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2024-11-25T15:08:32.264720", "lvl": "target", "msg": "p..."}
- {"dt": "2024-11-25T15:08:32.265025", "lvl": "target", "msg": "         Starting Create List of [   17.601121] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2024-11-25T15:08:32.265296", "lvl": "target", "msg": "Static Device Nodes..."}
- {"dt": "2024-11-25T15:08:32.267549", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.621143] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2024-11-25T15:08:32.268104", "lvl": "target", "msg": "ule configfs..."}
- {"dt": "2024-11-25T15:08:32.290076", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.645114] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2024-11-25T15:08:32.290582", "lvl": "target", "msg": "ule drm..."}
- {"dt": "2024-11-25T15:08:32.332783", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.665036] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2024-11-25T15:08:32.333312", "lvl": "target", "msg": "ule fuse..."}
- {"dt": "2024-11-25T15:08:32.333616", "lvl": "target", "msg": "         Starting SELinux autorel[   17.684894] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2024-11-25T15:08:32.333887", "lvl": "target", "msg": "abel service loading..."}
- {"dt": "2024-11-25T15:08:32.336191", "lvl": "target", "msg": "[   17.704300] fuse: init (API version 7.39)"}
- {"dt": "2024-11-25T15:08:32.378590", "lvl": "target", "msg": "         Starting SELinux init fo[   17.719976] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2024-11-25T15:08:32.379145", "lvl": "target", "msg": "r /dev service loading..."}
- {"dt": "2024-11-25T15:08:32.381724", "lvl": "target", "msg": "[   17.737015] systemd[1]: Starting Journal Service..."}
- {"dt": "2024-11-25T15:08:32.382138", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2024-11-25T15:08:32.397507", "lvl": "target", "msg": "[   17.762756] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2024-11-25T15:08:32.457025", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.775973] systemd[1]: Generate network units from Kernel command line was skipped because of an unmet condition check (ConditionKernelCommandLine=!nbd.server)."}
- {"dt": "2024-11-25T15:08:32.457535", "lvl": "target", "msg": "ules..."}
- {"dt": "2024-11-25T15:08:32.457839", "lvl": "target", "msg": "[   17.794167] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-11-25T15:08:32.458119", "lvl": "target", "msg": "         Starting Remount Root an[   17.808924] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2024-11-25T15:08:32.458381", "lvl": "target", "msg": "d Kernel File Systems..."}
- {"dt": "2024-11-25T15:08:32.487244", "lvl": "target", "msg": "         Starting Coldplug All udev Devic[   17.829559] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2024-11-25T15:08:32.487720", "lvl": "target", "msg": "es..."}
- {"dt": "2024-11-25T15:08:32.490366", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2024-11-25T15:08:32.534137", "lvl": "target", "msg": "[   17.864438] systemd[1]: Mounted Kernel Debug File System."}
- {"dt": "2024-11-25T15:08:32.535340", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2024-11-25T15:08:32.535682", "lvl": "target", "msg": "[   17.879937] EXT4-fs (nbd0): re-mounted 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w. Quota mode: none."}
- {"dt": "2024-11-25T15:08:32.537442", "lvl": "target", "msg": "[   17.892645] systemd[1]: Mounted Kernel Trace File System."}
- {"dt": "2024-11-25T15:08:32.537856", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2024-11-25T15:08:32.564183", "lvl": "target", "msg": "[   17.916011] systemd[1]: Mounted Temporary Directory /tmp."}
- {"dt": "2024-11-25T15:08:32.567277", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp[   17.923428] systemd-journald[197]: Collecting audit messages is enabled."}
- {"dt": "2024-11-25T15:08:32.567724", "lvl": "target", "msg": "."}
- {"dt": "2024-11-25T15:08:32.611991", "lvl": "target", "msg": "[   17.944157] systemd[1]: Finished Create List of Static Device Nodes."}
- {"dt": "2024-11-25T15:08:32.612470", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2024-11-25T15:08:32.612779", "lvl": "target", "msg": "[   17.958289] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-11-25T15:08:32.613059", "lvl": "target", "msg": "[   17.968907] snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored"}
- {"dt": "2024-11-25T15:08:32.663178", "lvl": "target", "msg": "[   17.989608] systemd[1]: Started Journal Service."}
- {"dt": "2024-11-25T15:08:32.663695", "lvl": "target", "msg": "[   17.991872] audit: type=1130 audit(1725908514.883:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.664053", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2024-11-25T15:08:32.715839", "lvl": "target", "msg": "[   18.023991] audit: type=1130 audit(1725908514.919:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.716365", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2024-11-25T15:08:32.716678", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Mod[   18.056957] audit: type=1130 audit(1725908514.951:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.717002", "lvl": "target", "msg": "ule drm."}
- {"dt": "2024-11-25T15:08:32.761612", "lvl": "target", "msg": "[   18.078310] audit: type=1131 audit(1725908514.951:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.762145", "lvl": "target", "msg": "[   18.100582] audit: type=1130 audit(1725908514.995:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.764767", "lvl": "target", "msg": "[   18.119107] audit: type=1131 audit(1725908514.995:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.811203", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2024-11-25T15:08:32.811722", "lvl": "target", "msg": "[   18.147878] audit: type=1130 audit(1725908515.043:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.814625", "lvl": "target", "msg": "[   18.166487] audit: type=1131 audit(1725908515.043:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.829982", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-11-25T15:08:32.878766", "lvl": "target", "msg": "[   18.203863] audit: type=1130 audit(1725908515.099:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=selinux-autorelabel comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.879297", "lvl": "target", "msg": "[   18.223077] audit: type=1131 audit(1725908515.099:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=selinux-autorelabel comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-11-25T15:08:32.881992", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-11-25T15:08:32.920579", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2024-11-25T15:08:32.921109", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2024-11-25T15:08:32.923990", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2024-11-25T15:08:32.955822", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2024-11-25T15:08:32.971827", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2024-11-25T15:08:32.987761", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2024-11-25T15:08:33.003299", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2024-11-25T15:08:33.030489", "lvl": "target", "msg": "[   18.384788] systemd-journald[197]: Received client request to flush runtime journal."}
- {"dt": "2024-11-25T15:08:33.086207", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev gracefully..."}
- {"dt": "2024-11-25T15:08:33.086713", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2024-11-25T15:08:33.102034", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2024-11-25T15:08:33.134397", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2024-11-25T15:08:33.134881", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2024-11-25T15:08:33.158247", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev gracefully."}
- {"dt": "2024-11-25T15:08:33.189859", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2024-11-25T15:08:33.270064", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2024-11-25T15:08:33.301980", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2024-11-25T15:08:33.317575", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2024-11-25T15:08:33.390599", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2024-11-25T15:08:33.391124", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2024-11-25T15:08:33.393760", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2024-11-25T15:08:33.450352", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2024-11-25T15:08:33.450884", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2024-11-25T15:08:33.451194", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2024-11-25T15:08:33.451467", "lvl": "target", "msg": "         Starting Load/Save OS Random Seed..."}
- {"dt": "2024-11-25T15:08:33.453470", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2024-11-25T15:08:33.469378", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2024-11-25T15:08:33.485295", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-11-25T15:08:33.501251", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-11-25T15:08:33.517253", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2024-11-25T15:08:33.558029", "lvl": "target", "msg": "[  OK  ] Finished Load/Save OS Random Seed."}
- {"dt": "2024-11-25T15:08:33.558499", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-11-25T15:08:33.561194", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2024-11-25T15:08:33.593096", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-11-25T15:08:33.640908", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2024-11-25T15:08:33.672858", "lvl": "target", "msg": "         Starting Load Audit Rules..."}
- {"dt": "2024-11-25T15:08:33.688788", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2024-11-25T15:08:33.928282", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2024-11-25T15:08:33.992147", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2024-11-25T15:08:34.071972", "lvl": "target", "msg": "[  OK  ] Finished Load Audit Rules."}
- {"dt": "2024-11-25T15:08:34.103974", "lvl": "target", "msg": "         Starting Security Audit Logging Service..."}
- {"dt": "2024-11-25T15:08:34.167605", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2024-11-25T15:08:34.199756", "lvl": "target", "msg": "[  OK  ] Started Security Audit Logging Service."}
- {"dt": "2024-11-25T15:08:34.231672", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2024-11-25T15:08:34.311557", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2024-11-25T15:08:34.432700", "lvl": "target", "msg": "[   19.762405] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040"}
- {"dt": "2024-11-25T15:08:34.433244", "lvl": "target", "msg": "[   19.770472] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1"}
- {"dt": "2024-11-25T15:08:34.433560", "lvl": "target", "msg": "[   19.770556] input: pwr_button as /devices/platform/pwr_button/input/input0"}
- {"dt": "2024-11-25T15:08:34.433835", "lvl": "target", "msg": "[   19.778147] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040"}
- {"dt": "2024-11-25T15:08:34.434091", "lvl": "target", "msg": "[   19.778265] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2"}
- {"dt": "2024-11-25T15:08:34.436084", "lvl": "target", "msg": "[   19.778292] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020"}
- {"dt": "2024-11-25T15:08:34.487120", "lvl": "target", "msg": "[   19.808961] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3"}
- {"dt": "2024-11-25T15:08:34.487599", "lvl": "target", "msg": "[   19.816680] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020"}
- {"dt": "2024-11-25T15:08:34.487938", "lvl": "target", "msg": "[   19.824545] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4"}
- {"dt": "2024-11-25T15:08:34.488218", "lvl": "target", "msg": "[   19.832435] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000"}
- {"dt": "2024-11-25T15:08:34.488475", "lvl": "target", "msg": "[   19.833919] macb 1f00100000.ethernet end0: renamed from eth0 (while UP)"}
- {"dt": "2024-11-25T15:08:34.490362", "lvl": "target", "msg": "[   19.840276] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0"}
- {"dt": "2024-11-25T15:08:34.554008", "lvl": "target", "msg": "[   19.919405] mc: Linux media interface: v0.10"}
- {"dt": "2024-11-25T15:08:34.612845", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-11-25T15:08:34.616036", "lvl": "target", "msg": "[  OK  ] Stopped Virtual Console Setup."}
- {"dt": "2024-11-25T15:08:34.634721", "lvl": "target", "msg": "[   19.991905] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-11-25T15:08:34.637867", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-11-25T15:08:34.668742", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2024-11-25T15:08:34.780206", "lvl": "target", "msg": "[   20.105277] Bluetooth: Core ver 2.22"}
- {"dt": "2024-11-25T15:08:34.780713", "lvl": "target", "msg": "[   20.108955] NET: Registered PF_BLUETOOTH protocol family"}
- {"dt": "2024-11-25T15:08:34.781024", "lvl": "target", "msg": "[   20.119880] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-11-25T15:08:34.781295", "lvl": "target", "msg": "[   20.126637] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-11-25T15:08:34.781552", "lvl": "target", "msg": "[   20.131707] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-11-25T15:08:34.781799", "lvl": "target", "msg": "[   20.136936] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-11-25T15:08:34.782038", "lvl": "target", "msg": "[   20.137143] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:34.783339", "lvl": "target", "msg": "[   20.150344] pispbe 1000880000.pisp_be: pispbe_probe: HW version:  0x02252700"}
- {"dt": "2024-11-25T15:08:34.823950", "lvl": "target", "msg": "[   20.151605] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-11-25T15:08:34.824453", "lvl": "target", "msg": "[   20.157533] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000"}
- {"dt": "2024-11-25T15:08:34.824767", "lvl": "target", "msg": "[   20.174642] pispbe 1000880000.pisp_be: pispbe_probe: Status:      0x00000000"}
- {"dt": "2024-11-25T15:08:34.825041", "lvl": "target", "msg": "[   20.181823] pispbe 1000880000.pisp_be: Register nodes for group 0"}
- {"dt": "2024-11-25T15:08:34.825299", "lvl": "target", "msg": "[   20.183739] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-11-25T15:08:34.827160", "lvl": "target", "msg": "[   20.188249] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-11-25T15:08:34.867505", "lvl": "target", "msg": "[   20.192009] pispbe 1000880000.pisp_be: input device node registered as /dev/video20"}
- {"dt": "2024-11-25T15:08:34.868018", "lvl": "target", "msg": "[   20.192045] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21"}
- {"dt": "2024-11-25T15:08:34.868350", "lvl": "target", "msg": "[   20.192064] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22"}
- {"dt": "2024-11-25T15:08:34.868628", "lvl": "target", "msg": "[   20.192083] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23"}
- {"dt": "2024-11-25T15:08:34.868883", "lvl": "target", "msg": "[   20.192100] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24"}
- {"dt": "2024-11-25T15:08:34.910891", "lvl": "target", "msg": "[   20.192116] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25"}
- {"dt": "2024-11-25T15:08:34.911356", "lvl": "target", "msg": "[   20.192133] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26"}
- {"dt": "2024-11-25T15:08:34.911665", "lvl": "target", "msg": "[   20.192150] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27"}
- {"dt": "2024-11-25T15:08:34.911967", "lvl": "target", "msg": "[   20.192169] pispbe 1000880000.pisp_be: config device node registered as /dev/video28"}
- {"dt": "2024-11-25T15:08:34.912227", "lvl": "target", "msg": "[   20.192397] pispbe 1000880000.pisp_be: Register nodes for group 1"}
- {"dt": "2024-11-25T15:08:34.914128", "lvl": "target", "msg": "[   20.192420] pispbe 1000880000.pisp_be: input device node registered as /dev/video29"}
- {"dt": "2024-11-25T15:08:34.954906", "lvl": "target", "msg": "[   20.192437] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30"}
- {"dt": "2024-11-25T15:08:34.955376", "lvl": "target", "msg": "[   20.192453] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31"}
- {"dt": "2024-11-25T15:08:34.955678", "lvl": "target", "msg": "[   20.192469] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32"}
- {"dt": "2024-11-25T15:08:34.955976", "lvl": "target", "msg": "[   20.192486] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33"}
- {"dt": "2024-11-25T15:08:34.956237", "lvl": "target", "msg": "[   20.192502] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34"}
- {"dt": "2024-11-25T15:08:34.998441", "lvl": "target", "msg": "[   20.192523] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35"}
- {"dt": "2024-11-25T15:08:34.998938", "lvl": "target", "msg": "[   20.192542] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36"}
- {"dt": "2024-11-25T15:08:34.999282", "lvl": "target", "msg": "[   20.192558] pispbe 1000880000.pisp_be: config device node registered as /dev/video37"}
- {"dt": "2024-11-25T15:08:34.999595", "lvl": "target", "msg": "[   20.196681] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-11-25T15:08:34.999893", "lvl": "target", "msg": "[   20.198649] rpivid 1000800000.codec: Device registered as /dev/video19"}
- {"dt": "2024-11-25T15:08:35.000159", "lvl": "target", "msg": "[   20.205951] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-11-25T15:08:35.041751", "lvl": "target", "msg": "[   20.213212] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'"}
- {"dt": "2024-11-25T15:08:35.042223", "lvl": "target", "msg": "[   20.238346] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2024-11-25T15:08:35.042530", "lvl": "target", "msg": "[   20.251303] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'"}
- {"dt": "2024-11-25T15:08:35.042803", "lvl": "target", "msg": "[   20.256357] Bluetooth: HCI UART protocol Three-wire (H5) registered"}
- {"dt": "2024-11-25T15:08:35.043059", "lvl": "target", "msg": "[   20.264987] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-11-25T15:08:35.043307", "lvl": "target", "msg": "[   20.272601] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator"}
- {"dt": "2024-11-25T15:08:35.085982", "lvl": "target", "msg": "[   20.278962] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2024-11-25T15:08:35.086460", "lvl": "target", "msg": "[   20.286372] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-11-25T15:08:35.086769", "lvl": "target", "msg": "[   20.286477] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator"}
- {"dt": "2024-11-25T15:08:35.087042", "lvl": "target", "msg": "[   20.343990] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-11-25T15:08:35.087299", "lvl": "target", "msg": "[   20.442089] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-11-25T15:08:35.089118", "lvl": "target", "msg": "[  OK  ] Finished Virtual Console Setup."}
- {"dt": "2024-11-25T15:08:35.184690", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-11-25T15:08:35.263521", "lvl": "target", "msg": "[   20.583032] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-11-25T15:08:35.264218", "lvl": "target", "msg": "[   20.592300] usbcore: registered new interface driver brcmfmac"}
- {"dt": "2024-11-25T15:08:35.264893", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch[   20.599898] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2"}
- {"dt": "2024-11-25T15:08:35.265374", "lvl": "target", "msg": " Status."}
- {"dt": "2024-11-25T15:08:35.266706", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/bthelper."}
- {"dt": "2024-11-25T15:08:35.303422", "lvl": "target", "msg": "[   20.648247] Bluetooth: hci0: BCM: chip id 107"}
- {"dt": "2024-11-25T15:08:35.303979", "lvl": "target", "msg": "[   20.652852] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-11-25T15:08:35.304405", "lvl": "target", "msg": "[   20.658541] Bluetooth: hci0: BCM4345C0"}
- {"dt": "2024-11-25T15:08:35.306596", "lvl": "target", "msg": "[   20.662306] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000"}
- {"dt": "2024-11-25T15:08:35.335333", "lvl": "target", "msg": "[   20.687132] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch"}
- {"dt": "2024-11-25T15:08:35.335910", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-11-25T15:08:35.354354", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2024-11-25T15:08:35.370299", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-11-25T15:08:35.386298", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-11-25T15:08:35.402209", "lvl": "target", "msg": "         Starting Network Name Resolution..."}
- {"dt": "2024-11-25T15:08:35.452973", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-11-25T15:08:35.453471", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-11-25T15:08:35.453816", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-11-25T15:08:35.456195", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-11-25T15:08:35.510957", "lvl": "target", "msg": "[   20.857227] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)"}
- {"dt": "2024-11-25T15:08:35.514177", "lvl": "target", "msg": "[   20.865282] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b"}
- {"dt": "2024-11-25T15:08:35.625794", "lvl": "target", "msg": "[  OK  ] Started Network Name Resolution."}
- {"dt": "2024-11-25T15:08:36.048777", "lvl": "target", "msg": "[   21.388472] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-11-25T15:08:36.049347", "lvl": "target", "msg": "[   21.394384] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190"}
- {"dt": "2024-11-25T15:08:36.049725", "lvl": "target", "msg": "[   21.400593] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382"}
- {"dt": "2024-11-25T15:08:36.052077", "lvl": "target", "msg": "[   21.406831] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)"}
- {"dt": "2024-11-25T15:08:37.880225", "lvl": "target", "msg": "[*     ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (6s / 1min 30s)"}
- {"dt": "2024-11-25T15:08:38.380040", "lvl": "target", "msg": "M[K[**    ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (6s / 1min 30s)"}
- {"dt": "2024-11-25T15:08:38.879989", "lvl": "target", "msg": "M[K[***   ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (7s / 1min 30s)"}
- {"dt": "2024-11-25T15:08:39.379915", "lvl": "target", "msg": "M[K[ ***  ] (2 of 2) A start job is running for\u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2024-11-25T15:08:39.879931", "lvl": "target", "msg": "M[K[  *** ] (2 of 2) A start job is running for\u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2024-11-25T15:08:40.087690", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2024-11-25T15:08:40.119805", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2024-11-25T15:08:40.135765", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2024-11-25T15:08:40.162436", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2024-11-25T15:08:40.165577", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2024-11-25T15:08:40.221306", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2024-11-25T15:08:40.221841", "lvl": "target", "msg": "[  OK  ] Listening on agl-compositor, a Wayl\u2026ositor, as a systemd user service."}
- {"dt": "2024-11-25T15:08:40.222154", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2024-11-25T15:08:40.224470", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2024-11-25T15:08:40.263302", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2024-11-25T15:08:40.263819", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2024-11-25T15:08:40.291280", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Manager Socket..."}
- {"dt": "2024-11-25T15:08:40.291803", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2024-11-25T15:08:40.307213", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2024-11-25T15:08:40.330208", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Manager Socket."}
- {"dt": "2024-11-25T15:08:40.346264", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2024-11-25T15:08:40.346802", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2024-11-25T15:08:40.362249", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2024-11-25T15:08:40.378176", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2024-11-25T15:08:40.417665", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2024-11-25T15:08:40.418223", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2024-11-25T15:08:40.420821", "lvl": "target", "msg": "         Starting Raspberry Pi bluetooth helper..."}
- {"dt": "2024-11-25T15:08:40.436715", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2024-11-25T15:08:40.477566", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2024-11-25T15:08:40.478043", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2024-11-25T15:08:40.480691", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2024-11-25T15:08:40.496617", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2024-11-25T15:08:40.512544", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2024-11-25T15:08:40.528552", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2024-11-25T15:08:40.551386", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (audio)."}
- {"dt": "2024-11-25T15:08:40.570435", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2024-11-25T15:08:40.604298", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2024-11-25T15:08:40.607478", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (video-capture)."}
- {"dt": "2024-11-25T15:08:40.607953", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2024-11-25T15:08:40.623356", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2024-11-25T15:08:40.639340", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2024-11-25T15:08:40.655300", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2024-11-25T15:08:40.697036", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2024-11-25T15:08:40.697513", "lvl": "target", "msg": "[  OK  ] Finished Raspberry Pi bluetooth helper."}
- {"dt": "2024-11-25T15:08:40.700224", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2024-11-25T15:08:40.716117", "lvl": "target", "msg": "[   26.081800] CAN device driver interface"}
- {"dt": "2024-11-25T15:08:40.760809", "lvl": "target", "msg": "[   26.106370] vcan: Virtual CAN interface driver"}
- {"dt": "2024-11-25T15:08:40.764073", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2024-11-25T15:08:40.764738", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2024-11-25T15:08:42.041363", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2024-11-25T15:08:42.280604", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2024-11-25T15:08:42.328423", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2024-11-25T15:08:42.328905", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2024-11-25T15:08:42.344384", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2024-11-25T15:08:42.360395", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2024-11-25T15:08:42.543872", "lvl": "target", "msg": "[   27.891200] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2024-11-25T15:08:42.544423", "lvl": "target", "msg": "[   27.896682] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2024-11-25T15:08:42.547191", "lvl": "target", "msg": "[   27.902111] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2024-11-25T15:08:42.547652", "lvl": "target", "msg": "[   27.908910] Bluetooth: MGMT ver 1.22"}
- {"dt": "2024-11-25T15:08:42.610873", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2024-11-25T15:08:42.738688", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2024-11-25T15:08:42.834310", "lvl": "target", "msg": "[   28.185993] Bluetooth: RFCOMM TTY layer initialized"}
- {"dt": "2024-11-25T15:08:42.834820", "lvl": "target", "msg": "[   28.191014] Bluetooth: RFCOMM socket layer initialized"}
- {"dt": "2024-11-25T15:08:42.837556", "lvl": "target", "msg": "[   28.196265] Bluetooth: RFCOMM ver 1.11"}
- {"dt": "2024-11-25T15:08:42.869331", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2024-11-25T15:08:42.885248", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2024-11-25T15:08:42.933204", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2024-11-25T15:08:42.949160", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2024-11-25T15:08:42.973160", "lvl": "target", "msg": "[   28.327693] pipewire[478]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set"}
- {"dt": "2024-11-25T15:08:43.052867", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val databroker."}
- {"dt": "2024-11-25T15:08:43.068843", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2024-11-25T15:08:43.069316", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2024-11-25T15:08:43.084756", "lvl": "target", "msg": "         Starting agl-vss-helper.service..."}
- {"dt": "2024-11-25T15:08:43.100754", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2024-11-25T15:08:43.116758", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2024-11-25T15:08:43.180624", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2024-11-25T15:08:43.212598", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2024-11-25T15:08:43.260450", "lvl": "target", "msg": "         Starting AGL compositor..."}
- {"dt": "2024-11-25T15:08:43.276419", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2024-11-25T15:08:43.303390", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttyAMA10."}
- {"dt": "2024-11-25T15:08:43.306508", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2024-11-25T15:08:43.689635", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2024-11-25T15:08:44.041032", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-11-25T15:08:44.056918", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-11-25T15:08:45.142687", "lvl": "target", "msg": "[  OK  ] Finished OpenSSH Key Generation."}
- {"dt": "2024-11-25T15:08:45.206451", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2024-11-25T15:08:45.478048", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2024-11-25T15:08:45.509881", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2024-11-25T15:08:45.621647", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2024-11-25T15:08:45.653554", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2024-11-25T15:08:45.919907", "lvl": "target", "msg": "[   31.276808] can: controller area network core"}
- {"dt": "2024-11-25T15:08:45.923014", "lvl": "target", "msg": "[   31.281334] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-11-25T15:08:45.954827", "lvl": "target", "msg": "[   31.325669] can: raw protocol"}
- {"dt": "2024-11-25T15:08:45.970725", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2024-11-25T15:08:46.018659", "lvl": "target", "msg": "         Starting applaunchd.service..."}
- {"dt": "2024-11-25T15:08:46.050711", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2024-11-25T15:08:46.098596", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2024-11-25T15:08:46.641432", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2024-11-25T15:08:46.816944", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2024-11-25T15:08:47.040325", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2024-11-25T15:08:47.177990", "lvl": "target", "msg": "[FAILED] Failed to start AGL compositor."}
- {"dt": "2024-11-25T15:08:47.181162", "lvl": "target", "msg": "See 'systemctl status agl-compositor.service' for details."}
- {"dt": "2024-11-25T15:08:47.181713", "lvl": "target", "msg": "[DEPEND] Dependency failed for homescreen.service."}
- {"dt": "2024-11-25T15:08:47.197057", "lvl": "target", "msg": "[DEPEND] Dependency failed for launcher.service."}
- {"dt": "2024-11-25T15:08:49.396314", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:49.396889", "lvl": "target", "msg": "Automotive Grade Linux 18.0.2 raspberrypi5 ttyAMA10"}
- {"dt": "2024-11-25T15:08:49.397226", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:49.400389", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2024-11-25T15:08:49.400883", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "34.72", "extra": {"extra": []}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:08:49.401507", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-11-25T15:08:49.401830", "lvl": "input", "msg": "root\n"}
- {"dt": "2024-11-25T15:08:49.502497", "lvl": "target", "msg": "raspberrypi5 login: root"}
- {"dt": "2024-11-25T15:08:49.503137", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-11-25T15:08:49.503596", "lvl": "debug", "msg": "login-action: Wait for prompt ['raspberrypi5:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:07:24)"}
- {"dt": "2024-11-25T15:08:49.511062", "lvl": "target", "msg": "root"}
- {"dt": "2024-11-25T15:08:52.848780", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-11-25T15:08:52.849447", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:00:38) [common]"}
- {"dt": "2024-11-25T15:08:52.849850", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "38.17", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:08:52.850541", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:01:44) [common]"}
- {"dt": "2024-11-25T15:08:52.850982", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:07:20) [common]"}
- {"dt": "2024-11-25T15:08:52.851337", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-11-25T15:08:52.851632", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-11-25T15:08:52.851986", "lvl": "input", "msg": "\n"}
- {"dt": "2024-11-25T15:08:52.902561", "lvl": "target", "msg": "7[r[999;999H[6nroot@raspberrypi5:~# "}
- {"dt": "2024-11-25T15:08:52.903254", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2024-11-25T15:08:52.903714", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2024-11-25T15:08:52.912359", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:52.928746", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:08:52.929380", "lvl": "debug", "msg": "start: 2.5 overlay-unpack (timeout 00:07:20) [common]"}
- {"dt": "2024-11-25T15:08:52.929800", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-11-25T15:08:53.030714", "lvl": "target", "msg": "root@raspberrypi5:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.040049", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.067170", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2024-11-25T15:08:53.068063", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/37784/compress-overlay-ia6ysko1/overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-11-25T15:08:53.168997", "lvl": "target", "msg": "root@raspberrypi5:~# wget http://192.168.66.1/tmp/37784/compress-overlay-ia6ysko1/overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.177727", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/37784/compress-overlay-ia6ysko11/overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.220329", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2024-11-25T15:08:53.220856", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2024-11-25T15:08:53.221163", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  8022  0:00:00 ETA"}
- {"dt": "2024-11-25T15:08:53.221441", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2024-11-25T15:08:53.221952", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-11-25T15:08:53.322607", "lvl": "target", "msg": "root@raspberrypi5:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.331490", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-11-25T15:08:53.394967", "lvl": "target", "msg": "x lava-37784/"}
- {"dt": "2024-11-25T15:08:53.395502", "lvl": "target", "msg": "x lava-37784/0/"}
- {"dt": "2024-11-25T15:08:53.435300", "lvl": "target", "msg": "x lava-37784/0/lava-test-runner.conf"}
- {"dt": "2024-11-25T15:08:53.435849", "lvl": "target", "msg": "x lava-37784/0/tests/"}
- {"dt": "2024-11-25T15:08:53.436188", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/"}
- {"dt": "2024-11-25T15:08:53.436458", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/inline/"}
- {"dt": "2024-11-25T15:08:53.436715", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/inline/dmesg.yaml"}
- {"dt": "2024-11-25T15:08:53.436962", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/run.sh"}
- {"dt": "2024-11-25T15:08:53.437201", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/testdef.yaml"}
- {"dt": "2024-11-25T15:08:53.437438", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/testdef_metadata"}
- {"dt": "2024-11-25T15:08:53.437675", "lvl": "target", "msg": "x lava-37784/0/tests/0_dmesg/uuid"}
- {"dt": "2024-11-25T15:08:53.437910", "lvl": "target", "msg": "x lava-37784/1/"}
- {"dt": "2024-11-25T15:08:53.438143", "lvl": "target", "msg": "x lava-37784/1/lava-test-runner.conf"}
- {"dt": "2024-11-25T15:08:53.438383", "lvl": "target", "msg": "x lava-37784/1/tests/"}
- {"dt": "2024-11-25T15:08:53.438624", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/"}
- {"dt": "2024-11-25T15:08:53.439145", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/inline/"}
- {"dt": "2024-11-25T15:08:53.478659", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/inline/bootrr.yaml"}
- {"dt": "2024-11-25T15:08:53.479217", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/run.sh"}
- {"dt": "2024-11-25T15:08:53.479523", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/testdef.yaml"}
- {"dt": "2024-11-25T15:08:53.479792", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/testdef_metadata"}
- {"dt": "2024-11-25T15:08:53.480083", "lvl": "target", "msg": "x lava-37784/1/tests/1_bootrr/uuid"}
- {"dt": "2024-11-25T15:08:53.480332", "lvl": "target", "msg": "x lava-37784/bin/"}
- {"dt": "2024-11-25T15:08:53.480576", "lvl": "target", "msg": "x lava-37784/bin/lava-add-keys"}
- {"dt": "2024-11-25T15:08:53.480824", "lvl": "target", "msg": "x lava-37784/bin/lava-add-sources"}
- {"dt": "2024-11-25T15:08:53.481065", "lvl": "target", "msg": "x lava-37784/bin/lava-background-process-start"}
- {"dt": "2024-11-25T15:08:53.481306", "lvl": "target", "msg": "x lava-37784/bin/lava-background-process-stop"}
- {"dt": "2024-11-25T15:08:53.481549", "lvl": "target", "msg": "x lava-37784/bin/lava-common-functions"}
- {"dt": "2024-11-25T15:08:53.481796", "lvl": "target", "msg": "x lava-37784/bin/lava-echo-ipv4"}
- {"dt": "2024-11-25T15:08:53.482329", "lvl": "target", "msg": "x lava-37784/bin/lava-install-packages"}
- {"dt": "2024-11-25T15:08:53.521584", "lvl": "target", "msg": "x lava-37784/bin/lava-installed-packages"}
- {"dt": "2024-11-25T15:08:53.521816", "lvl": "target", "msg": "x lava-37784/bin/lava-os-build"}
- {"dt": "2024-11-25T15:08:53.521933", "lvl": "target", "msg": "x lava-37784/bin/lava-probe-channel"}
- {"dt": "2024-11-25T15:08:53.522041", "lvl": "target", "msg": "x lava-37784/bin/lava-probe-ip"}
- {"dt": "2024-11-25T15:08:53.522143", "lvl": "target", "msg": "x lava-37784/bin/lava-target-ip"}
- {"dt": "2024-11-25T15:08:53.522241", "lvl": "target", "msg": "x lava-37784/bin/lava-target-mac"}
- {"dt": "2024-11-25T15:08:53.522338", "lvl": "target", "msg": "x lava-37784/bin/lava-target-storage"}
- {"dt": "2024-11-25T15:08:53.522433", "lvl": "target", "msg": "x lava-37784/bin/lava-test-case"}
- {"dt": "2024-11-25T15:08:53.522529", "lvl": "target", "msg": "x lava-37784/bin/lava-test-event"}
- {"dt": "2024-11-25T15:08:53.522625", "lvl": "target", "msg": "x lava-37784/bin/lava-test-feedback"}
- {"dt": "2024-11-25T15:08:53.522721", "lvl": "target", "msg": "x lava-37784/bin/lava-test-raise"}
- {"dt": "2024-11-25T15:08:53.522816", "lvl": "target", "msg": "x lava-37784/bin/lava-test-reference"}
- {"dt": "2024-11-25T15:08:53.522911", "lvl": "target", "msg": "x lava-37784/bin/lava-test-runner"}
- {"dt": "2024-11-25T15:08:53.524615", "lvl": "target", "msg": "x lava-37784/bin/lava-test-set"}
- {"dt": "2024-11-25T15:08:53.547622", "lvl": "target", "msg": "x lava-37784/bin/lava-test-shell"}
- {"dt": "2024-11-25T15:08:53.548187", "lvl": "target", "msg": "x lava-37784/environment"}
- {"dt": "2024-11-25T15:08:53.548500", "lvl": "target", "msg": "x lava-37784/lava"}
- {"dt": "2024-11-25T15:08:53.548768", "lvl": "target", "msg": "x lava-37784/lava.pub"}
- {"dt": "2024-11-25T15:08:53.549019", "lvl": "target", "msg": "x lava-37784/results/"}
- {"dt": "2024-11-25T15:08:53.549262", "lvl": "target", "msg": "x lava-37784/tests/"}
- {"dt": "2024-11-25T15:08:53.551161", "lvl": "debug", "msg": "end: 2.5 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:53.551693", "lvl": "debug", "msg": "start: 2.6 export-device-env (timeout 00:07:20) [common]"}
- {"dt": "2024-11-25T15:08:53.552178", "lvl": "debug", "msg": "end: 2.6 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-11-25T15:08:53.552586", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:01:52) [common]"}
- {"dt": "2024-11-25T15:08:53.552975", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-11-25T15:08:53.553354", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-11-25T15:08:53.553673", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-11-25T15:08:53.553989", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-11-25T15:08:53.654873", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-11-25T15:08:53.655600", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-11-25T15:08:53.662667", "lvl": "target", "msg": "#"}
- {"dt": "2024-11-25T15:08:53.678972", "lvl": "debug", "msg": "Using /lava-37784"}
- {"dt": "2024-11-25T15:08:53.679487", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-11-25T15:08:53.780413", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh"}
- {"dt": "2024-11-25T15:08:53.790425", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-11-25T15:08:53.806534", "lvl": "input", "msg": ". /lava-37784/environment\n"}
- {"dt": "2024-11-25T15:08:53.907675", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-37784/environment"}
- {"dt": "2024-11-25T15:08:53.918100", "lvl": "target", "msg": ". /lava-37784/environment"}
- {"dt": "2024-11-25T15:08:53.934303", "lvl": "input", "msg": "/lava-37784/bin/lava-test-runner /lava-37784/0\n"}
- {"dt": "2024-11-25T15:08:54.035433", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-37784/bin/lava-test-runner /lava-37784/0"}
- {"dt": "2024-11-25T15:08:54.036179", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-11-25T15:08:54.045857", "lvl": "target", "msg": "/lava-37784/bin/lava-test-runner /lava-37784/0"}
- {"dt": "2024-11-25T15:08:54.077406", "lvl": "target", "msg": "+ export TESTRUN_ID=0_dmesg"}
- {"dt": "2024-11-25T15:08:54.117825", "lvl": "target", "msg": "+ TESTRUN_ID=0_dmesg"}
- {"dt": "2024-11-25T15:08:54.118337", "lvl": "target", "msg": "+ cd /lava-37784/0/tests/0_dmesg"}
- {"dt": "2024-11-25T15:08:54.118643", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-11-25T15:08:54.118919", "lvl": "target", "msg": "+ UUID=37784_1.5.4.1"}
- {"dt": "2024-11-25T15:08:54.119179", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-11-25T15:08:54.119428", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_dmesg 37784_1.5.4.1>"}
- {"dt": "2024-11-25T15:08:54.119709", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-11-25T15:08:54.119989", "lvl": "target", "msg": "+ dmesg --level=warn --notime -x -k"}
- {"dt": "2024-11-25T15:08:54.120239", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-11-25T15:08:54.120483", "lvl": "target", "msg": "+ dmesg --level=err --notime -x -k"}
- {"dt": "2024-11-25T15:08:54.120728", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-11-25T15:08:54.120975", "lvl": "target", "msg": "+ dmesg --level=crit --notime -x -k"}
- {"dt": "2024-11-25T15:08:54.121213", "lvl": "target", "msg": "+ test -s dmesg.crit"}
- {"dt": "2024-11-25T15:08:54.121453", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-11-25T15:08:54.121691", "lvl": "target", "msg": "++ cat dmesg.crit"}
- {"dt": "2024-11-25T15:08:54.121933", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-11-25T15:08:54.122172", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-11-25T15:08:54.122688", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_dmesg 37784_1.5.4.1"}
- {"dt": "2024-11-25T15:08:54.122980", "lvl": "info", "msg": "Starting test lava.0_dmesg (37784_1.5.4.1)"}
- {"dt": "2024-11-25T15:08:54.123226", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-11-25T15:08:54.123332", "lvl": "target", "msg": "+ lava-test-case crit --result pass --measurement 0 --units lines"}
- {"dt": "2024-11-25T15:08:54.161230", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-11-25T15:08:54.161758", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-11-25T15:08:54.162071", "lvl": "target", "msg": "+ dmesg --level=alert --notime -x -k"}
- {"dt": "2024-11-25T15:08:54.162345", "lvl": "target", "msg": "+ test -s dmesg.alert"}
- {"dt": "2024-11-25T15:08:54.162598", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-11-25T15:08:54.162845", "lvl": "target", "msg": "++ cat dmesg.alert"}
- {"dt": "2024-11-25T15:08:54.163092", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-11-25T15:08:54.163337", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-11-25T15:08:54.163578", "lvl": "target", "msg": "+ lava-test-case alert --result pass --measurement 0 --units lines"}
- {"dt": "2024-11-25T15:08:54.163823", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-11-25T15:08:54.164109", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-11-25T15:08:54.164362", "lvl": "target", "msg": "+ dmesg --level=emerg --notime -x -k"}
- {"dt": "2024-11-25T15:08:54.164601", "lvl": "target", "msg": "+ test -s dmesg.emerg"}
- {"dt": "2024-11-25T15:08:54.165148", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-11-25T15:08:54.165641", "lvl": "results", "msg": {"case": "crit", "definition": "0_dmesg", "endtc": ! "1336", "measurement": ! "0.0", "result": "pass", "starttc": ! "1336", "units": "lines"}}
- {"dt": "2024-11-25T15:08:54.166298", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-11-25T15:08:54.166662", "lvl": "results", "msg": {"case": "alert", "definition": "0_dmesg", "endtc": ! "1338", "measurement": ! "0.0", "result": "pass", "starttc": ! "1338", "units": "lines"}}
- {"dt": "2024-11-25T15:08:54.167258", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-11-25T15:08:54.167527", "lvl": "target", "msg": "++ cat dmesg.emerg"}
- {"dt": "2024-11-25T15:08:54.167773", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-11-25T15:08:54.168053", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-11-25T15:08:54.203351", "lvl": "target", "msg": "+ lava-test-case emerg --result pass --measurement 0 --units lines"}
- {"dt": "2024-11-25T15:08:54.203907", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-11-25T15:08:54.204222", "lvl": "target", "msg": "+ cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"}
- {"dt": "2024-11-25T15:08:54.204501", "lvl": "target", "msg": "kern  :warn  : F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-11-25T15:08:54.204756", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-11-25T15:08:54.205268", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-11-25T15:08:54.205628", "lvl": "results", "msg": {"case": "emerg", "definition": "0_dmesg", "endtc": ! "1349", "measurement": ! "0.0", "result": "pass", "starttc": ! "1349", "units": "lines"}}
- {"dt": "2024-11-25T15:08:54.206567", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_dmesg 37784_1.5.4.1>"}
- {"dt": "2024-11-25T15:08:54.206974", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-11-25T15:08:54.207514", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_dmesg 37784_1.5.4.1"}
- {"dt": "2024-11-25T15:08:54.207892", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-11-25T15:08:54.208172", "lvl": "info", "msg": "Ending test lava.0_dmesg (37784_1.5.4.1), duration 0.09"}
- {"dt": "2024-11-25T15:08:54.208582", "lvl": "results", "msg": {"case": "0_dmesg", "definition": "lava", "duration": "0.09", "namespace": "common", "path": "inline/dmesg.yaml", "repository": {"metadata": {"description": "baseline test plan", "environment": ["lava-test-shell"], "format": "Lava-Test Test Definition 1.0", "name": "baseline", "os": ["debian"], "scope": ["functional"]}, "run": {"steps": ["for level in warn err; do\n  dmesg --level=$level --notime -x -k > dmesg.$level\ndone\n", "for level in crit alert emerg; do\n  dmesg --level=$level --notime -x -k > dmesg.$level\n  test -s dmesg.$level && res=fail || res=pass\n  count=$(cat dmesg.$level | wc -l)\n  lava-test-case $level \\\n    --result $res \\\n    --measurement $count \\\n    --units lines\ndone\n", "cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"]}}, "result": "pass", "revision": "unspecified", "uuid": "37784_1.5.4.1"}}
- {"dt": "2024-11-25T15:08:54.209586", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-11-25T15:08:54.210024", "lvl": "debug", "msg": "alert: pass\ncrit: pass\nemerg: pass\n"}
- {"dt": "2024-11-25T15:08:54.210408", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:54.210769", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:54.211128", "lvl": "info", "msg": "start: 4 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-11-25T15:08:54.211483", "lvl": "debug", "msg": "start: 4.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-11-25T15:08:54.211757", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-11-25T15:08:54.212063", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-11-25T15:08:54.312860", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-11-25T15:08:54.313550", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-11-25T15:08:54.314104", "lvl": "debug", "msg": "Using /lava-37784"}
- {"dt": "2024-11-25T15:08:54.314463", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-11-25T15:08:54.415270", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-11-25T15:08:54.416084", "lvl": "target", "msg": "#"}
- {"dt": "2024-11-25T15:08:54.416743", "lvl": "input", "msg": ". /lava-37784/environment\n"}
- {"dt": "2024-11-25T15:08:54.517674", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh. /lava-37784/environment"}
- {"dt": "2024-11-25T15:08:54.518476", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:54.519112", "lvl": "input", "msg": "/lava-37784/bin/lava-test-runner /lava-37784/1\n"}
- {"dt": "2024-11-25T15:08:54.619810", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-37784/environment/lava-37784/bin/lava-test-runner /lava-37784/1"}
- {"dt": "2024-11-25T15:08:54.620690", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-11-25T15:08:54.621389", "lvl": "target", "msg": ""}
- {"dt": "2024-11-25T15:08:54.637509", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-37784/bin/lava-test-runner /lava-37784/1"}
- {"dt": "2024-11-25T15:08:54.680539", "lvl": "target", "msg": "+ export TESTRUN_ID=1_bootrr"}
- {"dt": "2024-11-25T15:08:54.693669", "lvl": "target", "msg": "+ TESTRUN_ID=[   40.024596] <LAVA_SIGNAL_STARTRUN 1_bootrr 37784_1.5.4.5>"}
- {"dt": "2024-11-25T15:08:54.694213", "lvl": "target", "msg": "1_bootrr"}
- {"dt": "2024-11-25T15:08:54.694531", "lvl": "target", "msg": "+ cd /lava-37784/1/tests[   40.032885] <LAVA_SIGNAL_ENDRUN 1_bootrr 37784_1.5.4.5>"}
- {"dt": "2024-11-25T15:08:54.694811", "lvl": "target", "msg": "/1_bootrr"}
- {"dt": "2024-11-25T15:08:54.695065", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-11-25T15:08:54.695316", "lvl": "target", "msg": "+ UUID=37784_1.5.4.5"}
- {"dt": "2024-11-25T15:08:54.695562", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-11-25T15:08:54.695806", "lvl": "target", "msg": "+ export PATH=/opt/bootrr/helpers:/lava-37784/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-11-25T15:08:54.696093", "lvl": "target", "msg": "+ PATH=/opt/bootrr/helpers:/lava-37784/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-11-25T15:08:54.696342", "lvl": "target", "msg": "+ cd /opt/bootrr"}
- {"dt": "2024-11-25T15:08:54.696866", "lvl": "debug", "msg": "Received signal: <STARTRUN> 1_bootrr 37784_1.5.4.5"}
- {"dt": "2024-11-25T15:08:54.697185", "lvl": "info", "msg": "Starting test lava.1_bootrr (37784_1.5.4.5)"}
- {"dt": "2024-11-25T15:08:54.697515", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-11-25T15:08:54.697911", "lvl": "debug", "msg": "Received signal: <ENDRUN> 1_bootrr 37784_1.5.4.5"}
- {"dt": "2024-11-25T15:08:54.698213", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-11-25T15:08:54.698466", "lvl": "info", "msg": "Ending test lava.1_bootrr (37784_1.5.4.5), duration 0.00"}
- {"dt": "2024-11-25T15:08:54.698708", "lvl": "results", "msg": {"case": "1_bootrr", "definition": "lava", "duration": "0.00", "namespace": "common", "path": "inline/bootrr.yaml", "repository": {"metadata": {"description": "baseline test plan", "environment": ["lava-test-shell"], "format": "Lava-Test Test Definition 1.0", "name": "baseline", "os": ["debian"], "scope": ["functional"]}, "run": {"steps": ["export PATH=/opt/bootrr/helpers:$PATH", "cd /opt/bootrr && sh helpers/bootrr-auto"]}}, "result": "pass", "revision": "unspecified", "uuid": "37784_1.5.4.5"}}
- {"dt": "2024-11-25T15:08:54.714163", "lvl": "target", "msg": "/lava-37784/1/tests/1_bootrr/run.sh: line 15: cd: /opt/bootrr: No such file or directory"}
- {"dt": "2024-11-25T15:08:54.714695", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-11-25T15:08:54.717328", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-11-25T15:08:54.717969", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-11-25T15:08:54.718444", "lvl": "debug", "msg": "end: 4.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:54.718834", "lvl": "info", "msg": "end: 4 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:54.719213", "lvl": "info", "msg": "start: 5 finalize (timeout 00:07:18) [common]"}
- {"dt": "2024-11-25T15:08:54.719604", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-11-25T15:08:54.720147", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'off' '2'"}
- {"dt": "2024-11-25T15:08:55.762242", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-11-25T15:08:55.762902", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-11-25T15:08:55.764181", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-11-25T15:08:55.864859", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:55.865431", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "1.15", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-11-25T15:08:55.866152", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:07:17) [common]"}
- {"dt": "2024-11-25T15:08:55.867072", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-11-25T15:08:55.867769", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-11-25T15:08:56.868068", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-11-25T15:08:56.868735", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-11-25T15:08:56.869106", "lvl": "target", "msg": "root@raspberrypi5:~# \0"}
- {"dt": "2024-11-25T15:08:56.970132", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-11-25T15:08:56.970708", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-11-25T15:08:56.971045", "lvl": "debug", "msg": "clean NBD port 61974"}
- {"dt": "2024-11-25T15:08:57.083753", "lvl": "info", "msg": "end: 5 finalize (duration 00:00:02) [common]"}
- {"dt": "2024-11-25T15:08:57.085004", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-11-25T15:08:57.085515", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/initrd"}
- {"dt": "2024-11-25T15:08:57.089158", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/kernel"}
- {"dt": "2024-11-25T15:08:57.092236", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/dtb"}
- {"dt": "2024-11-25T15:08:57.092487", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37784/nbd-deploy-hgnn6_6r/nbdroot"}
- {"dt": "2024-11-25T15:08:57.314352", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/37784"}
- {"dt": "2024-11-25T15:08:57.315862", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/37784"}
- {"dt": "2024-11-25T15:08:57.316044", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-11-25T15:08:57.316125", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
