- {"dt": "2024-12-02T13:00:52.654342", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.654739", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.654873", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655004", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655111", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655251", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655368", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655491", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.655614", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-12-02T13:00:52.669053", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-02T13:00:52.669162", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-02T13:00:52.669314", "lvl": "info", "msg": "Start time: 2024-12-02 13:00:52.669304+00:00 (UTC)"}
- {"dt": "2024-12-02T13:00:52.669497", "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-12-02T13:00:52.951661", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-12-02T13:00:53.155126", "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-12-02T13:00:53.364397", "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-12-02T13:00:53.572259", "lvl": "info", "msg": "validate duration: 0.90"}
- {"dt": "2024-12-02T13:00:53.572402", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-02T13:00:53.572582", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T13:00:53.572670", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-02T13:00:53.572754", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-02T13:00:53.572844", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-02T13:00:53.573008", "lvl": "debug", "msg": "Set_port 61986"}
- {"dt": "2024-12-02T13:00:53.573116", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T13:00:53.573214", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T13:00:53.573451", "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-12-02T13:00:53.573549", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T13:00:53.573634", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-12-02T13:00:53.573715", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-12-02T13:00:53.874997", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.073211", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.170688", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.267242", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.270312", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.279972", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.365077", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.367762", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.375661", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.377131", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.387384", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.388171", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.394349", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.464647", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.482384", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.484488", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.495077", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.495910", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.528268", "lvl": "info", "msg": "2\u202FMB downloaded in 0.95\u202Fs (2.15\u202FMB/s)"}
- {"dt": "2024-12-02T13:00:54.528620", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:00:54.528710", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.96", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:00:54.528935", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:00:54.529028", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-12-02T13:00:54.529119", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-12-02T13:00:54.529316", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-12-02T13:00:54.529392", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/kernel/Image"}
- {"dt": "2024-12-02T13:00:54.529455", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-12-02T13:00:54.529519", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-02T13:00:54.831982", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.341625", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.451698", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.544654", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.571676", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.708546", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.715555", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.812057", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.860396", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-02T13:00:55.936547", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.090318", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.104653", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.201965", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.389213", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.397674", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.500295", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.618641", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.736453", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.971127", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-12-02T13:00:56.978147", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.091019", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.091631", "lvl": "info", "msg": "27\u202FMB downloaded in 2.56\u202Fs (10.75\u202FMB/s)"}
- {"dt": "2024-12-02T13:00:57.092040", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-12-02T13:00:57.092227", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.56", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:00:57.092616", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-12-02T13:00:57.092845", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T13:00:57.093037", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T13:00:57.093398", "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-12-02T13:00:57.093551", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-02T13:00:57.093675", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.093796", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-02T13:00:57.393758", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.492077", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.493746", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.494547", "lvl": "info", "msg": "0\u202FMB downloaded in 0.40\u202Fs (0.19\u202FMB/s)"}
- {"dt": "2024-12-02T13:00:57.495199", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:00:57.495526", "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-12-02T13:00:57.496241", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:00:57.496614", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T13:00:57.496776", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T13:00:57.496969", "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-12-02T13:00:57.497072", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T13:00:57.497137", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-12-02T13:00:57.497201", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-02T13:00:57.802974", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T13:01:00.188066", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-12-02T13:01:01.845283", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-12-02T13:01:03.539990", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-12-02T13:01:05.317386", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-12-02T13:01:06.988258", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-12-02T13:01:09.468655", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-12-02T13:01:11.272188", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-12-02T13:01:13.239363", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-12-02T13:01:14.791130", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-12-02T13:01:16.393700", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-12-02T13:01:17.999623", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-12-02T13:01:19.845073", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-12-02T13:01:21.658190", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-12-02T13:01:23.512760", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-12-02T13:01:25.681758", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-12-02T13:01:27.423841", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-12-02T13:01:29.078919", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-12-02T13:01:31.023905", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-12-02T13:01:32.890742", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-12-02T13:01:36.838404", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-12-02T13:01:38.269437", "lvl": "info", "msg": "470\u202FMB downloaded in 40.77\u202Fs (11.55\u202FMB/s)"}
- {"dt": "2024-12-02T13:01:38.269790", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:41) [common]"}
- {"dt": "2024-12-02T13:01:38.269891", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "40.77", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.270105", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:41) [common]"}
- {"dt": "2024-12-02T13:01:38.270218", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.270516", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m"}
- {"dt": "2024-12-02T13:01:38.270711", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin"}
- {"dt": "2024-12-02T13:01:38.270849", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/tests"}
- {"dt": "2024-12-02T13:01:38.270977", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/results"}
- {"dt": "2024-12-02T13:01:38.271113", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-add-keys"}
- {"dt": "2024-12-02T13:01:38.271326", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-add-sources"}
- {"dt": "2024-12-02T13:01:38.271527", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-background-process-start"}
- {"dt": "2024-12-02T13:01:38.271714", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-background-process-stop"}
- {"dt": "2024-12-02T13:01:38.271917", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-common-functions"}
- {"dt": "2024-12-02T13:01:38.272110", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-echo-ipv4"}
- {"dt": "2024-12-02T13:01:38.272298", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-install-packages"}
- {"dt": "2024-12-02T13:01:38.272488", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-installed-packages"}
- {"dt": "2024-12-02T13:01:38.272672", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-os-build"}
- {"dt": "2024-12-02T13:01:38.272882", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-probe-channel"}
- {"dt": "2024-12-02T13:01:38.273034", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-probe-ip"}
- {"dt": "2024-12-02T13:01:38.273180", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-target-ip"}
- {"dt": "2024-12-02T13:01:38.273323", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-target-mac"}
- {"dt": "2024-12-02T13:01:38.273467", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-target-storage"}
- {"dt": "2024-12-02T13:01:38.273619", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-case"}
- {"dt": "2024-12-02T13:01:38.273765", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-event"}
- {"dt": "2024-12-02T13:01:38.273909", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-feedback"}
- {"dt": "2024-12-02T13:01:38.274053", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-raise"}
- {"dt": "2024-12-02T13:01:38.274208", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-reference"}
- {"dt": "2024-12-02T13:01:38.274356", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-runner"}
- {"dt": "2024-12-02T13:01:38.274498", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-set"}
- {"dt": "2024-12-02T13:01:38.274639", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-test-shell"}
- {"dt": "2024-12-02T13:01:38.274785", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-02T13:01:38.274976", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-02T13:01:38.275121", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/environment"}
- {"dt": "2024-12-02T13:01:38.275240", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-02T13:01:38.275315", "lvl": "debug", "msg": "- LAVA_JOB_ID=38059"}
- {"dt": "2024-12-02T13:01:38.275380", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-12-02T13:01:38.275499", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.275833", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.275934", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.276004", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-02T13:01:38.276082", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.276160", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.276222", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-02T13:01:38.276295", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.276372", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.276444", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-02T13:01:38.276532", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.276604", "lvl": "debug", "msg": "Using /lava-38059 at stage 0"}
- {"dt": "2024-12-02T13:01:38.277074", "lvl": "debug", "msg": "uuid=38059_1.5.4.1 testdef=None"}
- {"dt": "2024-12-02T13:01:38.277171", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.277255", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.277888", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.277975", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38059_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.278136", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.278932", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.279015", "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": "38059_1.5.4.1", "uuid": "38059_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.279179", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.280023", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/0/tests/0_dmesg test_uuid 38059_1.5.4.1"}
- {"dt": "2024-12-02T13:01:38.280222", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.280303", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38059_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.280468", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.280538", "lvl": "debug", "msg": "Using /lava-38059 at stage 1"}
- {"dt": "2024-12-02T13:01:38.280980", "lvl": "debug", "msg": "uuid=38059_1.5.4.5 testdef=None"}
- {"dt": "2024-12-02T13:01:38.281086", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.281170", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.281721", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.281794", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38059_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.281946", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.282734", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.282814", "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": "38059_1.5.4.5", "uuid": "38059_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.282975", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.283736", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/1/tests/1_bootrr test_uuid 38059_1.5.4.5"}
- {"dt": "2024-12-02T13:01:38.283923", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.284001", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38059_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:38.284141", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-02T13:01:38.284203", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/0 for stage 0"}
- {"dt": "2024-12-02T13:01:38.284305", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-12-02T13:01:38.284394", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38059/lava-overlay-e5l2mi9m/lava-38059/1 for stage 1"}
- {"dt": "2024-12-02T13:01:38.284495", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-12-02T13:01:38.284597", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.284682", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.293925", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.294064", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.294166", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.294263", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.294358", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.294450", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.294550", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.294636", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.294723", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.294793", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-02T13:01:38.294864", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61986, NBD-ROOT: 38059/nbd-deploy-st1dn29v/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T13:01:38.295309", "lvl": "debug", "msg": "nbd-server 61986 /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T13:01:38.298134", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-02T13:01:38.298299", "lvl": "debug", "msg": "output: ** (process:4695): WARNING **: 13:01:38.297: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-02T13:01:38.298400", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-02T13:01:38.298484", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-02T13:01:38.298607", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.298731", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:00:45) [common]"}
- {"dt": "2024-12-02T13:01:38.298861", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.298986", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.299069", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63007'"}
- {"dt": "2024-12-02T13:01:38.302730", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-02T13:01:38.303293", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:01:38.303482", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.303616", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:09:15) [common]"}
- {"dt": "2024-12-02T13:01:38.303826", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'reset' '2'"}
- {"dt": "2024-12-02T13:01:39.327202", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T13:01:39.327811", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-02T13:01:43.316899", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T13:01:43.317788", "lvl": "debug", "msg": ">> Switched outlet 2 on\r"}
- {"dt": "2024-12-02T13:01:45.320442", "lvl": "debug", "msg": ">> export LANG='C'\r"}
- {"dt": "2024-12-02T13:01:45.321080", "lvl": "debug", "msg": ">> export LC_ALL='C.UTF-8'\r"}
- {"dt": "2024-12-02T13:01:45.321506", "lvl": "debug", "msg": ">> export PATH='/usr/local/bin:/usr/local/sbin:/bin:/usr/bin:/usr/sbin:/sbin'\r"}
- {"dt": "2024-12-02T13:01:45.321865", "lvl": "debug", "msg": ">> export PWD='/'\r"}
- {"dt": "2024-12-02T13:01:45.326183", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-02T13:01:45.330121", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp\r"}
- {"dt": "2024-12-02T13:01:45.337564", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.338087", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-02T13:01:45.338484", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.342864", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.349802", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.354867", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.358234", "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-12-02T13:01:45.358713", "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-12-02T13:01:45.415798", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61986      0.0.0.0:*    users:((\"nbd-server\",pid=4696,fd=3))\r"}
- {"dt": "2024-12-02T13:01:45.417512", "lvl": "debug", "msg": ">> DEBUG: PID=4696\r"}
- {"dt": "2024-12-02T13:01:45.424512", "lvl": "debug", "msg": ">> NBDPORT=61986\r"}
- {"dt": "2024-12-02T13:01:45.426076", "lvl": "debug", "msg": ">> basename: extra operand \u2018/var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\u2019\r"}
- {"dt": "2024-12-02T13:01:45.426215", "lvl": "debug", "msg": ">> Try 'basename --help' for more information.\r"}
- {"dt": "2024-12-02T13:01:45.426999", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-02T13:01:45.427140", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-02T13:01:45.427248", "lvl": "debug", "msg": ">> \t\tkernel 38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.427333", "lvl": "debug", "msg": ">> \t\tinitramfs \r"}
- {"dt": "2024-12-02T13:01:45.427412", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-02T13:01:45.428224", "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-12-02T13:01:45.428577", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/dtb/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-02T13:01:45.430473", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v\r"}
- {"dt": "2024-12-02T13:01:45.432006", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.434045", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=38059/nbd-deploy-st1dn29v/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.435895", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.438643", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.496970", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61986      0.0.0.0:*    users:((\"nbd-server\",pid=4696,fd=3))\r"}
- {"dt": "2024-12-02T13:01:45.498790", "lvl": "debug", "msg": ">> DEBUG: PID=4696\r"}
- {"dt": "2024-12-02T13:01:45.508814", "lvl": "debug", "msg": ">> NBDPORT=61986\r"}
- {"dt": "2024-12-02T13:01:45.513421", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-02T13:01:45.513706", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-02T13:01:45.513974", "lvl": "debug", "msg": ">> \t\tkernel 38059/nbd-deploy-st1dn29v/kernel/Image\r"}
- {"dt": "2024-12-02T13:01:45.514264", "lvl": "debug", "msg": ">> \t\tinitramfs initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T13:01:45.514471", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-02T13:01:45.517456", "lvl": "debug", "msg": "Returned 0 in 7 seconds"}
- {"dt": "2024-12-02T13:01:45.618572", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:07) [common]"}
- {"dt": "2024-12-02T13:01:45.619139", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "7.31", "extra": {"status": "success"}, "level": "2.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:01:45.619818", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:07) [common]"}
- {"dt": "2024-12-02T13:01:45.620233", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:09:08) [common]"}
- {"dt": "2024-12-02T13:01:45.620587", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-02T13:01:45.620950", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-12-02T13:01:45.621961", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-02T13:01:45.622285", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-02T13:01:45.622553", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-02T13:01:45.622810", "lvl": "target", "msg": "\0"}
- {"dt": "2024-12-02T13:01:45.623058", "lvl": "target", "msg": "  1.27 RPi: BOOTSYS release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-02T13:01:45.623307", "lvl": "target", "msg": "  1.31 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 1631478"}
- {"dt": "2024-12-02T13:01:45.623539", "lvl": "target", "msg": "  1.41 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-02T13:01:45.623597", "lvl": "target", "msg": "  1.49 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T13:01:45.623654", "lvl": "target", "msg": "  1.51 PM_RSTS: 0x00001000"}
- {"dt": "2024-12-02T13:01:45.623711", "lvl": "target", "msg": "  1.51 part 00000000 reset_info 00000000"}
- {"dt": "2024-12-02T13:01:45.623768", "lvl": "target", "msg": "  1.55 PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0"}
- {"dt": "2024-12-02T13:01:45.623825", "lvl": "target", "msg": "  1.61 uSD voltage 3.3V"}
- {"dt": "2024-12-02T13:01:45.623881", "lvl": "target", "msg": "  1.81 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)"}
- {"dt": "2024-12-02T13:01:45.623937", "lvl": "target", "msg": "  1.84 DDR 4267 1 0 64 152 BL:-1"}
- {"dt": "2024-12-02T13:01:47.362350", "lvl": "target", "msg": "  3.50 OTP boardrev d04170 bootrom a a"}
- {"dt": "2024-12-02T13:01:47.365662", "lvl": "target", "msg": "  3.51 Customer key hash 0000000000000000000000000000000000000000000000000000000000000000"}
- {"dt": "2024-12-02T13:01:47.366219", "lvl": "target", "msg": "  3.58 VC-JTAG unlocked"}
- {"dt": "2024-12-02T13:01:47.381470", "lvl": "target", "msg": "  3.81 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T13:01:47.924231", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:01:48.004169", "lvl": "target", "msg": "  4.09 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T13:01:48.020102", "lvl": "target", "msg": "  4.09 RP1_BOOT: fw size 46888"}
- {"dt": "2024-12-02T13:01:48.642668", "lvl": "target", "msg": "  4.47 PCI2 init"}
- {"dt": "2024-12-02T13:01:48.643214", "lvl": "target", "msg": "  4.47 PCI2 reset"}
- {"dt": "2024-12-02T13:01:48.722269", "lvl": "target", "msg": "  4.92 PCIe scan 00001de4:00000001"}
- {"dt": "2024-12-02T13:01:48.722758", "lvl": "target", "msg": "  4.93 RP1_CHIP_INFO 20001927"}
- {"dt": "2024-12-02T13:01:48.723054", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:01:48.723325", "lvl": "target", "msg": "  4.95 RPi: BOOTLOADER release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-02T13:01:48.725702", "lvl": "target", "msg": "  5.02 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 5002726"}
- {"dt": "2024-12-02T13:01:48.726226", "lvl": "target", "msg": "  5.12 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-02T13:01:48.780597", "lvl": "target", "msg": "  5.40 usb_pd_init status 3"}
- {"dt": "2024-12-02T13:01:48.781156", "lvl": "target", "msg": "  5.40 USB_PD CONFIG 0 41"}
- {"dt": "2024-12-02T13:01:48.781455", "lvl": "target", "msg": "  5.47 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:48.781719", "lvl": "target", "msg": "  5.47 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:48.781975", "lvl": "target", "msg": "  5.51 USBSTS 1"}
- {"dt": "2024-12-02T13:01:48.782215", "lvl": "target", "msg": "  5.54 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:48.782454", "lvl": "target", "msg": "  5.59 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T13:01:48.782692", "lvl": "target", "msg": "  5.71 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:48.782926", "lvl": "target", "msg": "  5.71 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:48.783159", "lvl": "target", "msg": "  5.75 USBSTS 1"}
- {"dt": "2024-12-02T13:01:48.783687", "lvl": "target", "msg": "  5.78 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:48.784025", "lvl": "target", "msg": "  5.83 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T13:01:48.799455", "lvl": "target", "msg": "  5.90 Boot mode: SD (01) order 24"}
- {"dt": "2024-12-02T13:01:49.327134", "lvl": "target", "msg": "  5.91 USB-PD: src-cap PDO object1 0x0a11912c"}
- {"dt": "2024-12-02T13:01:49.327671", "lvl": "target", "msg": "  5.92 Current 3000 mA"}
- {"dt": "2024-12-02T13:01:49.327975", "lvl": "target", "msg": "  5.94 Voltage 5000 mV"}
- {"dt": "2024-12-02T13:01:49.328242", "lvl": "target", "msg": "  5.96 USB-PD: src-cap PDO object2 0x0012d12c"}
- {"dt": "2024-12-02T13:01:49.328498", "lvl": "target", "msg": "  5.00 Current 3000 mA"}
- {"dt": "2024-12-02T13:01:49.328774", "lvl": "target", "msg": "  5.02 Voltage 9000 mV"}
- {"dt": "2024-12-02T13:01:49.329020", "lvl": "target", "msg": "  5.04 USB-PD: src-cap PDO object3 0x0014b12c"}
- {"dt": "2024-12-02T13:01:49.329270", "lvl": "target", "msg": "  5.09 Current 3000 mA"}
- {"dt": "2024-12-02T13:01:49.329509", "lvl": "target", "msg": "  5.11 Voltage 15000 mV"}
- {"dt": "2024-12-02T13:01:49.329746", "lvl": "target", "msg": "  5.13 USB-PD: src-cap PDO object4 0x00164145"}
- {"dt": "2024-12-02T13:01:49.329985", "lvl": "target", "msg": "  5.17 Current 3250 mA"}
- {"dt": "2024-12-02T13:01:49.330224", "lvl": "target", "msg": "  5.19 Voltage 20000 mV"}
- {"dt": "2024-12-02T13:01:49.359090", "lvl": "target", "msg": "  5.35 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:49.362483", "lvl": "target", "msg": "  5.46 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:49.457874", "lvl": "target", "msg": "  5.55 EMMC"}
- {"dt": "2024-12-02T13:01:49.553830", "lvl": "target", "msg": "  5.55 SD retry 1 oc 0"}
- {"dt": "2024-12-02T13:01:49.591985", "lvl": "target", "msg": "  5.80 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:49.687635", "lvl": "target", "msg": "  5.89 SD retry 2 oc 0"}
- {"dt": "2024-12-02T13:01:49.725704", "lvl": "target", "msg": "  6.13 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:49.821409", "lvl": "target", "msg": "  6.22 SDV1"}
- {"dt": "2024-12-02T13:01:49.935007", "lvl": "target", "msg": "  6.22 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-02T13:01:49.938342", "lvl": "target", "msg": "  6.23 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-02T13:01:49.938859", "lvl": "target", "msg": "  6.29 Retry SD 1"}
- {"dt": "2024-12-02T13:01:49.982908", "lvl": "target", "msg": "  6.60 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:49.986283", "lvl": "target", "msg": "  6.71 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:50.081783", "lvl": "target", "msg": "  6.80 EMMC"}
- {"dt": "2024-12-02T13:01:50.177560", "lvl": "target", "msg": "  6.80 SD retry 1 oc 0"}
- {"dt": "2024-12-02T13:01:50.216826", "lvl": "target", "msg": "  6.04 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:50.312439", "lvl": "target", "msg": "  6.13 SD retry 2 oc 0"}
- {"dt": "2024-12-02T13:01:50.349489", "lvl": "target", "msg": "  6.37 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T13:01:50.445118", "lvl": "target", "msg": "  6.46 SDV1"}
- {"dt": "2024-12-02T13:01:50.558694", "lvl": "target", "msg": "  6.46 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-02T13:01:50.561799", "lvl": "target", "msg": "  6.47 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-02T13:01:50.562221", "lvl": "target", "msg": "  6.54 Boot mode: USB-MSD (04) order 2"}
- {"dt": "2024-12-02T13:01:50.612065", "lvl": "target", "msg": "  6.71 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:50.612574", "lvl": "target", "msg": "  6.71 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.612922", "lvl": "target", "msg": "  6.76 USBSTS 0"}
- {"dt": "2024-12-02T13:01:50.613195", "lvl": "target", "msg": "  6.79 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:50.613446", "lvl": "target", "msg": "  6.79 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.613696", "lvl": "target", "msg": "  6.85 USBSTS 0"}
- {"dt": "2024-12-02T13:01:50.613938", "lvl": "target", "msg": "  6.93 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:50.614178", "lvl": "target", "msg": "  6.93 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.614417", "lvl": "target", "msg": "  6.97 USBSTS 1"}
- {"dt": "2024-12-02T13:01:50.614653", "lvl": "target", "msg": "  6.00 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.614900", "lvl": "target", "msg": "  6.05 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T13:01:50.615147", "lvl": "target", "msg": "  6.17 XHCI-STOP"}
- {"dt": "2024-12-02T13:01:50.638516", "lvl": "target", "msg": "  6.17 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.639044", "lvl": "target", "msg": "  6.21 USBSTS 1"}
- {"dt": "2024-12-02T13:01:50.639350", "lvl": "target", "msg": "  6.24 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T13:01:50.639622", "lvl": "target", "msg": "  6.29 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T13:02:15.650933", "lvl": "target", "msg": " 31.36 USB MSD stopped. Timeout: 25 seconds"}
- {"dt": "2024-12-02T13:02:15.651495", "lvl": "target", "msg": " 31.37 Boot mode: NETWORK (02) order 0"}
- {"dt": "2024-12-02T13:02:15.736867", "lvl": "target", "msg": " 32.24 NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.66.1"}
- {"dt": "2024-12-02T13:02:18.658595", "lvl": "target", "msg": " 34.50 LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-02T13:02:18.659143", "lvl": "target", "msg": " 34.51 Link ready"}
- {"dt": "2024-12-02T13:02:18.706482", "lvl": "target", "msg": " 34.95 RX: 0 IP: 0 IPV4: 0 MAC: 0 UDP: 0 UDP RECV: 0 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.729897", "lvl": "target", "msg": " 42.96 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.730442", "lvl": "target", "msg": " 42.97 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-02T13:02:26.730750", "lvl": "target", "msg": " 43.00 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.731018", "lvl": "target", "msg": " 43.02 OPTIONS:-"}
- {"dt": "2024-12-02T13:02:26.731264", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER 43.08 "}
- {"dt": "2024-12-02T13:02:26.731512", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.11 "}
- {"dt": "2024-12-02T13:02:26.731751", "lvl": "target", "msg": "\top: 51 len:   4  43.14 "}
- {"dt": "2024-12-02T13:02:26.731992", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.17 "}
- {"dt": "2024-12-02T13:02:26.732230", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1 43.20 "}
- {"dt": "2024-12-02T13:02:26.732468", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T13:02:26.732705", "lvl": "target", "msg": " 43.23 "}
- {"dt": "2024-12-02T13:02:26.732990", "lvl": "target", "msg": " 43.24 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.733242", "lvl": "target", "msg": " 43.30 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.733780", "lvl": "target", "msg": " 43.34 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-02T13:02:26.773101", "lvl": "target", "msg": " 43.37 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.773662", "lvl": "target", "msg": " 43.39 OPTIONS:-"}
- {"dt": "2024-12-02T13:02:26.773967", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK 43.45 "}
- {"dt": "2024-12-02T13:02:26.774240", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.48 "}
- {"dt": "2024-12-02T13:02:26.774494", "lvl": "target", "msg": "\top: 51 len:   4  43.50 "}
- {"dt": "2024-12-02T13:02:26.774739", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.54 "}
- {"dt": "2024-12-02T13:02:26.774983", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1 43.57 "}
- {"dt": "2024-12-02T13:02:26.775226", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1 43.60 "}
- {"dt": "2024-12-02T13:02:26.775769", "lvl": "target", "msg": "\top: 150 len:   4  43.62 "}
- {"dt": "2024-12-02T13:02:26.776035", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T13:02:26.776286", "lvl": "target", "msg": " 43.65 "}
- {"dt": "2024-12-02T13:02:26.776534", "lvl": "target", "msg": "\top: 15 len:  11  43.67 "}
- {"dt": "2024-12-02T13:02:26.776807", "lvl": "target", "msg": " 43.68 ARP 192.168.66.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-02T13:02:26.777052", "lvl": "target", "msg": " 43.72 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-02T13:02:26.816384", "lvl": "target", "msg": " 43.78 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.817007", "lvl": "target", "msg": " 43.85 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-02T13:02:26.817347", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:02:26.817624", "lvl": "target", "msg": " 43.91 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.817885", "lvl": "target", "msg": " 43.01 TFTP 1: File not found "}
- {"dt": "2024-12-02T13:02:26.818133", "lvl": "target", "msg": " 43.01 RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.818375", "lvl": "target", "msg": " 43.09 BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-02T13:02:26.818625", "lvl": "target", "msg": " 43.14 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 config.txt"}
- {"dt": "2024-12-02T13:02:26.818868", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:02:26.859320", "lvl": "target", "msg": " 43.18 RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.859866", "lvl": "target", "msg": " 43.29 TFTP: complete 137"}
- {"dt": "2024-12-02T13:02:26.860173", "lvl": "target", "msg": " 43.29 RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.860445", "lvl": "target", "msg": " 43.36 Read config.txt bytes      137 hnd 0x0"}
- {"dt": "2024-12-02T13:02:26.860698", "lvl": "target", "msg": " 43.40 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 pieeprom.sig"}
- {"dt": "2024-12-02T13:02:26.861002", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:02:26.862446", "lvl": "target", "msg": " 43.45 RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.924931", "lvl": "target", "msg": " 43.95 TFTP 1: File not found "}
- {"dt": "2024-12-02T13:02:26.925473", "lvl": "target", "msg": " 43.95 RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:26.925791", "lvl": "target", "msg": " 43.02 Read pieeprom.upd failed"}
- {"dt": "2024-12-02T13:02:26.926065", "lvl": "target", "msg": " 43.05 usb_max_current_enable default 0 max-current 3000"}
- {"dt": "2024-12-02T13:02:26.926329", "lvl": "target", "msg": " 43.10 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-02T13:02:26.926584", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:02:26.928129", "lvl": "target", "msg": " 43.16 RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:27.007033", "lvl": "target", "msg": " 43.77 TFTP: complete 78059"}
- {"dt": "2024-12-02T13:02:27.007559", "lvl": "target", "msg": " 43.77 RX: 62 IP: 0 IPV4: 61 MAC: 61 UDP: 61 UDP RECV: 61 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T13:02:27.007869", "lvl": "target", "msg": " 43.84 Read bcm2712-rpi-5-b.dtb bytes    78059 hnd 0x0"}
- {"dt": "2024-12-02T13:02:27.008141", "lvl": "target", "msg": " 43.89 dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712"}
- {"dt": "2024-12-02T13:02:27.008401", "lvl": "target", "msg": " 43.95 dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712"}
- {"dt": "2024-12-02T13:02:27.010122", "lvl": "target", "msg": " 43.02 Selecting USB low current limit"}
- {"dt": "2024-12-02T13:02:27.010532", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:02:55.176924", "lvl": "target", "msg": "NOTICE:  BL31: v2.6(release):v2.6-240-gfc45bc492-dirty"}
- {"dt": "2024-12-02T13:02:55.177473", "lvl": "target", "msg": "NOTICE:  BL31: Built : 13:41:55, Sep 23 2024"}
- {"dt": "2024-12-02T13:02:55.321449", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]"}
- {"dt": "2024-12-02T13:02:55.321957", "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-12-02T13:02:55.322266", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-12-02T13:02:55.323057", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:07:58) [common]"}
- {"dt": "2024-12-02T13:02:55.323449", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2024-12-02T13:02:55.323746", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-02T13:02:55.324065", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-12-02T13:02:55.324378", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-02T13:02:55.324639", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-12-02T13:02:55.324954", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-02T13:02:55.325197", "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-12-02T13:02:55.325670", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:07:58)"}
- {"dt": "2024-12-02T13:02:55.327014", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-12-02T13:02:55.327084", "lvl": "target", "msg": "[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0"}
- {"dt": "2024-12-02T13:02:55.327144", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-02T13:02:55.364702", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000002000000, size 64 MiB"}
- {"dt": "2024-12-02T13:02:55.365287", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-12-02T13:02:55.365586", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000002000000..0x0000000005ffffff (65536 KiB) map reusable linux,cma"}
- {"dt": "2024-12-02T13:02:55.366188", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0"}
- {"dt": "2024-12-02T13:02:55.366466", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x000000003fd16180..0x000000003fd1627a (0 KiB) nomap non-reusable nvram@0"}
- {"dt": "2024-12-02T13:02:55.368363", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-02T13:02:55.408108", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]"}
- {"dt": "2024-12-02T13:02:55.408675", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-12-02T13:02:55.409022", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T13:02:55.409297", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-02T13:02:55.409551", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-02T13:02:55.409796", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000007ffff]"}
- {"dt": "2024-12-02T13:02:55.410039", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]"}
- {"dt": "2024-12-02T13:02:55.410279", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T13:02:55.451400", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T13:02:55.451942", "lvl": "target", "msg": "[    0.000000] On node 0, zone DMA: 512 pages in unavailable ranges"}
- {"dt": "2024-12-02T13:02:55.452248", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-02T13:02:55.452518", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-02T13:02:55.452811", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-02T13:02:55.453062", "lvl": "target", "msg": "[    0.000000] psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2024-12-02T13:02:55.453304", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.2"}
- {"dt": "2024-12-02T13:02:55.454555", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 14 pages/cpu s184936 r8192 d36248 u229376"}
- {"dt": "2024-12-02T13:02:55.494676", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-02T13:02:55.495213", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-12-02T13:02:55.495525", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Hardware dirty bit management"}
- {"dt": "2024-12-02T13:02:55.495798", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-12-02T13:02:55.496053", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-12-02T13:02:55.496301", "lvl": "target", "msg": "[    0.000000] CPU features: kernel page table isolation forced ON by KASLR"}
- {"dt": "2024-12-02T13:02:55.496549", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)"}
- {"dt": "2024-12-02T13:02:55.497756", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-12-02T13:02:55.581378", "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=61986 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false rw systemd.mask=systemd-network-generator.service"}
- {"dt": "2024-12-02T13:02:55.581944", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \\\"verbose earlyprintk\\\", will be passed to user space."}
- {"dt": "2024-12-02T13:02:55.582256", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, linear)"}
- {"dt": "2024-12-02T13:02:55.582529", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, linear)"}
- {"dt": "2024-12-02T13:02:55.582784", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 521728"}
- {"dt": "2024-12-02T13:02:55.584534", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-12-02T13:02:55.624600", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 4."}
- {"dt": "2024-12-02T13:02:55.625163", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000fbffc000-0x00000000ffffc000] (64MB)"}
- {"dt": "2024-12-02T13:02:55.625475", "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-12-02T13:02:55.625751", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-12-02T13:02:55.626010", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 49047 entries in 48 pages"}
- {"dt": "2024-12-02T13:02:55.627742", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 48 pages with 2 groups"}
- {"dt": "2024-12-02T13:02:55.667904", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-12-02T13:02:55.668454", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-02T13:02:55.668808", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-12-02T13:02:55.669087", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-12-02T13:02:55.669346", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T13:02:55.669594", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T13:02:55.669835", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T13:02:55.670975", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-02T13:02:55.711303", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-12-02T13:02:55.711837", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-02T13:02:55.712146", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-12-02T13:02:55.712414", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-02T13:02:55.712667", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-12-02T13:02:55.712997", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-12-02T13:02:55.714386", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-12-02T13:02:55.754508", "lvl": "target", "msg": "[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-12-02T13:02:55.755041", "lvl": "target", "msg": "[    0.000117] Console: colour dummy device 80x25"}
- {"dt": "2024-12-02T13:02:55.755347", "lvl": "target", "msg": "[    0.000130] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-12-02T13:02:55.755621", "lvl": "target", "msg": "[    0.000133] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-02T13:02:55.755873", "lvl": "target", "msg": "[    0.000149] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-12-02T13:02:55.756119", "lvl": "target", "msg": "[    0.000193] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T13:02:55.797718", "lvl": "target", "msg": "[    0.000206] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T13:02:55.798254", "lvl": "target", "msg": "[    0.000456] cgroup: Disabling memory control group subsystem"}
- {"dt": "2024-12-02T13:02:55.798565", "lvl": "target", "msg": "[    0.000734] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T13:02:55.798838", "lvl": "target", "msg": "[    0.000747] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T13:02:55.799095", "lvl": "target", "msg": "[    0.000761] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T13:02:55.799341", "lvl": "target", "msg": "[    0.000795] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-02T13:02:55.800899", "lvl": "target", "msg": "[    0.000796] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-12-02T13:02:55.841189", "lvl": "target", "msg": "[    0.001175] EFI services will not be available."}
- {"dt": "2024-12-02T13:02:55.841743", "lvl": "target", "msg": "[    0.001215] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-02T13:02:55.842051", "lvl": "target", "msg": "[    0.001319] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-02T13:02:55.842322", "lvl": "target", "msg": "[    0.001348] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]"}
- {"dt": "2024-12-02T13:02:55.842578", "lvl": "target", "msg": "[    0.001468] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-02T13:02:55.842823", "lvl": "target", "msg": "[    0.001491] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]"}
- {"dt": "2024-12-02T13:02:55.843066", "lvl": "target", "msg": "[    0.001605] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-02T13:02:55.843303", "lvl": "target", "msg": "[    0.001624] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]"}
- {"dt": "2024-12-02T13:02:55.844226", "lvl": "target", "msg": "[    0.001644] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-12-02T13:02:55.884403", "lvl": "target", "msg": "[    0.001646] SMP: Total of 4 processors activated."}
- {"dt": "2024-12-02T13:02:55.884968", "lvl": "target", "msg": "[    0.001647] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-02T13:02:55.885277", "lvl": "target", "msg": "[    0.001649] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-12-02T13:02:55.885549", "lvl": "target", "msg": "[    0.001650] CPU features: detected: Common not Private translations"}
- {"dt": "2024-12-02T13:02:55.885805", "lvl": "target", "msg": "[    0.001651] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-02T13:02:55.886055", "lvl": "target", "msg": "[    0.001652] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-12-02T13:02:55.887550", "lvl": "target", "msg": "[    0.001653] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-12-02T13:02:55.927716", "lvl": "target", "msg": "[    0.001655] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-12-02T13:02:55.928251", "lvl": "target", "msg": "[    0.001655] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-12-02T13:02:55.928559", "lvl": "target", "msg": "[    0.001657] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-12-02T13:02:55.928871", "lvl": "target", "msg": "[    0.001680] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-02T13:02:55.929129", "lvl": "target", "msg": "[    0.001681] alternatives: applying system-wide alternatives"}
- {"dt": "2024-12-02T13:02:55.929375", "lvl": "target", "msg": "[    0.003116] devtmpfs: initialized"}
- {"dt": "2024-12-02T13:02:55.929613", "lvl": "target", "msg": "[    0.004930] Enabled cp15_barrier support"}
- {"dt": "2024-12-02T13:02:55.929851", "lvl": "target", "msg": "[    0.004933] Enabled setend support"}
- {"dt": "2024-12-02T13:02:55.970929", "lvl": "target", "msg": "[    0.004996] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-02T13:02:55.971459", "lvl": "target", "msg": "[    0.005000] futex hash table entries: 1024 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-02T13:02:55.971768", "lvl": "target", "msg": "[    0.005214] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-02T13:02:55.972040", "lvl": "target", "msg": "[    0.005297] DMI not present or invalid."}
- {"dt": "2024-12-02T13:02:55.972300", "lvl": "target", "msg": "[    0.005412] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-12-02T13:02:55.972550", "lvl": "target", "msg": "[    0.005722] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-12-02T13:02:55.974144", "lvl": "target", "msg": "[    0.005750] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-12-02T13:02:56.014324", "lvl": "target", "msg": "[    0.005777] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-12-02T13:02:56.014879", "lvl": "target", "msg": "[    0.005789] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-02T13:02:56.015193", "lvl": "target", "msg": "[    0.005825] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-02T13:02:56.015461", "lvl": "target", "msg": "[    0.005907] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-12-02T13:02:56.015712", "lvl": "target", "msg": "[    0.005914] cpuidle: using governor menu"}
- {"dt": "2024-12-02T13:02:56.015955", "lvl": "target", "msg": "[    0.005965] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-02T13:02:56.017513", "lvl": "target", "msg": "[    0.005983] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-02T13:02:56.075011", "lvl": "target", "msg": "[    0.006171] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-02T13:02:56.075554", "lvl": "target", "msg": "[    0.006663] bcm2835-mbox 107c013880.mailbox: mailbox enabled"}
- {"dt": "2024-12-02T13:02:56.075865", "lvl": "target", "msg": "[    0.006788] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-12-02T13:02:56.076139", "lvl": "target", "msg": "[    0.006795] printk: console [ttyAMA10] enabled"}
- {"dt": "2024-12-02T13:02:56.076395", "lvl": "target", "msg": "[    0.770853] raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-27T14:28:19, variant start_cd"}
- {"dt": "2024-12-02T13:02:56.078130", "lvl": "target", "msg": "[    0.784899] raspberrypi-firmware soc:firmware: Firmware hash is 0b72471b00000000000000000000000000000000"}
- {"dt": "2024-12-02T13:02:56.120579", "lvl": "target", "msg": "[    0.799745] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-12-02T13:02:56.121151", "lvl": "target", "msg": "[    0.806823] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-12-02T13:02:56.121463", "lvl": "target", "msg": "[    0.806824] Modules: 129224 pages in range for PLT usage"}
- {"dt": "2024-12-02T13:02:56.121731", "lvl": "target", "msg": "[    0.813827] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1"}
- {"dt": "2024-12-02T13:02:56.121985", "lvl": "target", "msg": "[    0.826452] iommu: Default domain type: Translated"}
- {"dt": "2024-12-02T13:02:56.122229", "lvl": "target", "msg": "[    0.831280] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-12-02T13:02:56.122472", "lvl": "target", "msg": "[    0.837721] SCSI subsystem initialized"}
- {"dt": "2024-12-02T13:02:56.123731", "lvl": "target", "msg": "[    0.841499] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-02T13:02:56.174440", "lvl": "target", "msg": "[    0.847006] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-02T13:02:56.174963", "lvl": "target", "msg": "[    0.852337] usbcore: registered new device driver usb"}
- {"dt": "2024-12-02T13:02:56.175270", "lvl": "target", "msg": "[    0.857460] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-02T13:02:56.175543", "lvl": "target", "msg": "[    0.862439] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-02T13:02:56.175802", "lvl": "target", "msg": "[    0.871607] PTP clock support registered"}
- {"dt": "2024-12-02T13:02:56.176049", "lvl": "target", "msg": "[    0.875572] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-02T13:02:56.176300", "lvl": "target", "msg": "[    0.881841] vgaarb: loaded"}
- {"dt": "2024-12-02T13:02:56.177598", "lvl": "target", "msg": "[    0.884636] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-02T13:02:56.309487", "lvl": "target", "msg": "[    0.992060] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-02T13:02:56.310010", "lvl": "target", "msg": "[    0.996002] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)"}
- {"dt": "2024-12-02T13:02:56.310316", "lvl": "target", "msg": "[    1.003093] FS-Cache: Loaded"}
- {"dt": "2024-12-02T13:02:56.310587", "lvl": "target", "msg": "[    1.006000] CacheFiles: Loaded"}
- {"dt": "2024-12-02T13:02:56.310839", "lvl": "target", "msg": "[    1.010023] NET: Registered PF_INET protocol family"}
- {"dt": "2024-12-02T13:02:56.311083", "lvl": "target", "msg": "[    1.015016] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.311328", "lvl": "target", "msg": "[    1.024129] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.354734", "lvl": "target", "msg": "[    1.032719] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.355263", "lvl": "target", "msg": "[    1.040491] TCP established hash table entries: 65536 (order: 5, 524288 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.355569", "lvl": "target", "msg": "[    1.048603] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.355843", "lvl": "target", "msg": "[    1.057517] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-02T13:02:56.356097", "lvl": "target", "msg": "[    1.064157] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.356347", "lvl": "target", "msg": "[    1.071813] UDP hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.398420", "lvl": "target", "msg": "[    1.078685] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T13:02:56.398938", "lvl": "target", "msg": "[    1.086030] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-12-02T13:02:56.399246", "lvl": "target", "msg": "[    1.091839] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-02T13:02:56.399513", "lvl": "target", "msg": "[    1.097779] RPC: Registered udp transport module."}
- {"dt": "2024-12-02T13:02:56.399765", "lvl": "target", "msg": "[    1.102495] RPC: Registered tcp transport module."}
- {"dt": "2024-12-02T13:02:56.400009", "lvl": "target", "msg": "[    1.107211] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-12-02T13:02:56.400249", "lvl": "target", "msg": "[    1.112716] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-02T13:02:56.401541", "lvl": "target", "msg": "[    1.119180] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-02T13:02:56.446507", "lvl": "target", "msg": "[    1.123280] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-12-02T13:02:56.447036", "lvl": "target", "msg": "[    1.127594] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)"}
- {"dt": "2024-12-02T13:02:56.447347", "lvl": "target", "msg": "[    1.127599] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-02T13:02:56.447623", "lvl": "target", "msg": "[    1.136350] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-12-02T13:02:56.447877", "lvl": "target", "msg": "[    1.141763] rootfs image is not initramfs (invalid magic at start of compressed archive); looks like an initrd"}
- {"dt": "2024-12-02T13:02:56.448128", "lvl": "target", "msg": "[    1.155717] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-12-02T13:02:56.448369", "lvl": "target", "msg": "[    1.165544] Initialise system trusted keyrings"}
- {"dt": "2024-12-02T13:02:56.449627", "lvl": "target", "msg": "[    1.168123] Freeing initrd memory: 12288K"}
- {"dt": "2024-12-02T13:02:56.489996", "lvl": "target", "msg": "[    1.170084] workingset: timestamp_bits=46 max_order=19 bucket_order=0"}
- {"dt": "2024-12-02T13:02:56.490525", "lvl": "target", "msg": "[    1.180513] zbud: loaded"}
- {"dt": "2024-12-02T13:02:56.490829", "lvl": "target", "msg": "[    1.183200] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-02T13:02:56.491096", "lvl": "target", "msg": "[    1.188269] Key type id_resolver registered"}
- {"dt": "2024-12-02T13:02:56.491350", "lvl": "target", "msg": "[    1.192461] Key type id_legacy registered"}
- {"dt": "2024-12-02T13:02:56.491593", "lvl": "target", "msg": "[    1.196482] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-02T13:02:56.491836", "lvl": "target", "msg": "[    1.203207] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-12-02T13:02:56.493173", "lvl": "target", "msg": "[    1.210653] F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-02T13:02:56.533592", "lvl": "target", "msg": "[    1.216199] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-12-02T13:02:56.534125", "lvl": "target", "msg": "[    1.221004] Key type asymmetric registered"}
- {"dt": "2024-12-02T13:02:56.534429", "lvl": "target", "msg": "[    1.225111] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-02T13:02:56.534700", "lvl": "target", "msg": "[    1.230013] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-12-02T13:02:56.534958", "lvl": "target", "msg": "[    1.237462] io scheduler mq-deadline registered"}
- {"dt": "2024-12-02T13:02:56.535204", "lvl": "target", "msg": "[    1.242004] io scheduler kyber registered"}
- {"dt": "2024-12-02T13:02:56.535449", "lvl": "target", "msg": "[    1.246027] io scheduler bfq registered"}
- {"dt": "2024-12-02T13:02:56.535687", "lvl": "target", "msg": "[    1.250028] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 26)"}
- {"dt": "2024-12-02T13:02:56.572561", "lvl": "target", "msg": "[    1.257757] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 27)"}
- {"dt": "2024-12-02T13:02:56.573099", "lvl": "target", "msg": "[    1.265467] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 28)"}
- {"dt": "2024-12-02T13:02:56.573412", "lvl": "target", "msg": "[    1.273178] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 29)"}
- {"dt": "2024-12-02T13:02:56.576047", "lvl": "target", "msg": "[    1.282456] bcm2708_fb soc:fb: FB found 1 display(s)"}
- {"dt": "2024-12-02T13:02:56.598673", "lvl": "target", "msg": "[    1.295816] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-02T13:02:56.601807", "lvl": "target", "msg": "[    1.304778] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1080"}
- {"dt": "2024-12-02T13:02:56.660164", "lvl": "target", "msg": "[    1.340847] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled"}
- {"dt": "2024-12-02T13:02:56.660699", "lvl": "target", "msg": "[    1.347397] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 32, base_baud = 6000000) is a Broadcom BCM7271 UART"}
- {"dt": "2024-12-02T13:02:56.661049", "lvl": "target", "msg": "[    1.357992] serial serial0: tty port ttyS0 registered"}
- {"dt": "2024-12-02T13:02:56.661322", "lvl": "target", "msg": "[    1.363232] iproc-rng200 107d208000.rng: hwrng registered"}
- {"dt": "2024-12-02T13:02:56.661575", "lvl": "target", "msg": "[    1.368676] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)"}
- {"dt": "2024-12-02T13:02:56.663597", "lvl": "target", "msg": "[    1.377114] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe"}
- {"dt": "2024-12-02T13:02:56.664124", "lvl": "target", "msg": "[    1.385710] brd: module loaded"}
- {"dt": "2024-12-02T13:02:56.708986", "lvl": "target", "msg": "[    1.389642] loop: module loaded"}
- {"dt": "2024-12-02T13:02:56.709519", "lvl": "target", "msg": "[    1.396583] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-12-02T13:02:56.709824", "lvl": "target", "msg": "[    1.403971] Loading iSCSI transport class v2.0-870."}
- {"dt": "2024-12-02T13:02:56.710096", "lvl": "target", "msg": "[    1.409415] usbcore: registered new device driver r8152-cfgselector"}
- {"dt": "2024-12-02T13:02:56.710349", "lvl": "target", "msg": "[    1.415710] usbcore: registered new interface driver r8152"}
- {"dt": "2024-12-02T13:02:56.710594", "lvl": "target", "msg": "[    1.421216] usbcore: registered new interface driver lan78xx"}
- {"dt": "2024-12-02T13:02:56.710834", "lvl": "target", "msg": "[    1.426933] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)"}
- {"dt": "2024-12-02T13:02:56.761279", "lvl": "target", "msg": "[    1.432794] usbcore: registered new interface driver uas"}
- {"dt": "2024-12-02T13:02:56.761838", "lvl": "target", "msg": "[    1.438126] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-02T13:02:56.762147", "lvl": "target", "msg": "[    1.444168] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-02T13:02:56.762419", "lvl": "target", "msg": "[    1.450723] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-02T13:02:56.762674", "lvl": "target", "msg": "[    1.456751] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-12-02T13:02:56.762920", "lvl": "target", "msg": "[    1.462342] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-12-02T13:02:56.763163", "lvl": "target", "msg": "[    1.468334] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2024-12-02T13:02:56.764456", "lvl": "target", "msg": "[    1.481474] rpi-rtc soc:rpi_rtc: registered as rtc0"}
- {"dt": "2024-12-02T13:02:56.807010", "lvl": "target", "msg": "[    1.487740] rpi-rtc soc:rpi_rtc: setting system clock to 1970-01-01T00:01:13 UTC (73)"}
- {"dt": "2024-12-02T13:02:56.807536", "lvl": "target", "msg": "[    1.495640] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-02T13:02:56.807840", "lvl": "target", "msg": "[    1.501964] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!"}
- {"dt": "2024-12-02T13:02:56.808110", "lvl": "target", "msg": "[    1.508515] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-12-02T13:02:56.808358", "lvl": "target", "msg": "[    1.516910] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-02T13:02:56.808604", "lvl": "target", "msg": "[    1.523116] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-02T13:02:56.810176", "lvl": "target", "msg": "[    1.527505] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-02T13:02:56.850902", "lvl": "target", "msg": "[    1.533324] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-02T13:02:56.851433", "lvl": "target", "msg": "[    1.539393] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ...."}
- {"dt": "2024-12-02T13:02:56.851745", "lvl": "target", "msg": "[    1.545866] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2024-12-02T13:02:56.852018", "lvl": "target", "msg": "[    1.551058] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-02T13:02:56.852273", "lvl": "target", "msg": "[    1.556648] usbhid: USB HID core driver"}
- {"dt": "2024-12-02T13:02:56.852517", "lvl": "target", "msg": "[    1.560740] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available"}
- {"dt": "2024-12-02T13:02:56.852792", "lvl": "target", "msg": "[    1.569045] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-02T13:02:56.899614", "lvl": "target", "msg": "[    1.575359] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-12-02T13:02:56.900134", "lvl": "target", "msg": "[    1.580446] Key type dns_resolver registered"}
- {"dt": "2024-12-02T13:02:56.900443", "lvl": "target", "msg": "[    1.588048] registered taskstats version 1"}
- {"dt": "2024-12-02T13:02:56.900708", "lvl": "target", "msg": "[    1.592208] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-02T13:02:56.900992", "lvl": "target", "msg": "[    1.598300] Key type .fscrypt registered"}
- {"dt": "2024-12-02T13:02:56.901237", "lvl": "target", "msg": "[    1.602233] Key type fscrypt-provisioning registered"}
- {"dt": "2024-12-02T13:02:56.901475", "lvl": "target", "msg": "[    1.607813] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges:"}
- {"dt": "2024-12-02T13:02:56.901717", "lvl": "target", "msg": "[    1.614814] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@120000, using [bus 00-ff]"}
- {"dt": "2024-12-02T13:02:56.944280", "lvl": "target", "msg": "[    1.623964] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000"}
- {"dt": "2024-12-02T13:02:56.944840", "lvl": "target", "msg": "[    1.632263] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000"}
- {"dt": "2024-12-02T13:02:56.945152", "lvl": "target", "msg": "[    1.640562] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000"}
- {"dt": "2024-12-02T13:02:56.945423", "lvl": "target", "msg": "[    1.648858] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000"}
- {"dt": "2024-12-02T13:02:56.945681", "lvl": "target", "msg": "[    1.658318] brcm-pcie 1000120000.pcie: Forcing gen 2"}
- {"dt": "2024-12-02T13:02:56.947407", "lvl": "target", "msg": "[    1.663482] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-12-02T13:02:56.988180", "lvl": "target", "msg": "[    1.669945] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-12-02T13:02:56.988710", "lvl": "target", "msg": "[    1.675448] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])"}
- {"dt": "2024-12-02T13:02:56.989056", "lvl": "target", "msg": "[    1.686013] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])"}
- {"dt": "2024-12-02T13:02:56.989334", "lvl": "target", "msg": "[    1.697195] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400"}
- {"dt": "2024-12-02T13:02:56.989589", "lvl": "target", "msg": "[    1.703239] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-12-02T13:02:57.006952", "lvl": "target", "msg": "[    1.709329] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-12-02T13:02:57.153131", "lvl": "target", "msg": "[    1.824640] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)"}
- {"dt": "2024-12-02T13:02:57.153658", "lvl": "target", "msg": "[    1.831293] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000"}
- {"dt": "2024-12-02T13:02:57.153964", "lvl": "target", "msg": "[    1.837330] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff]"}
- {"dt": "2024-12-02T13:02:57.154232", "lvl": "target", "msg": "[    1.843622] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff]"}
- {"dt": "2024-12-02T13:02:57.154483", "lvl": "target", "msg": "[    1.849914] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff]"}
- {"dt": "2024-12-02T13:02:57.154729", "lvl": "target", "msg": "[    1.856261] pci 0000:01:00.0: supports D1"}
- {"dt": "2024-12-02T13:02:57.154969", "lvl": "target", "msg": "[    1.860279] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold"}
- {"dt": "2024-12-02T13:02:57.196402", "lvl": "target", "msg": "[    1.876644] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-12-02T13:02:57.196957", "lvl": "target", "msg": "[    1.883286] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-02T13:02:57.197264", "lvl": "target", "msg": "[    1.890448] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff]"}
- {"dt": "2024-12-02T13:02:57.197535", "lvl": "target", "msg": "[    1.897611] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff]"}
- {"dt": "2024-12-02T13:02:57.197786", "lvl": "target", "msg": "[    1.904773] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff]"}
- {"dt": "2024-12-02T13:02:57.198028", "lvl": "target", "msg": "[    1.911936] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-12-02T13:02:57.199529", "lvl": "target", "msg": "[    1.916915] pci 0000:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-02T13:02:57.239881", "lvl": "target", "msg": "[    1.924077] pci 0000:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-02T13:02:57.240426", "lvl": "target", "msg": "[    1.932552] pci 0000:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-02T13:02:57.240775", "lvl": "target", "msg": "[    1.941067] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-02T13:02:57.241060", "lvl": "target", "msg": "[    1.947205] pcieport 0000:00:00.0: PME: Signaling with IRQ 37"}
- {"dt": "2024-12-02T13:02:57.241318", "lvl": "target", "msg": "[    1.953010] pcieport 0000:00:00.0: AER: enabled with IRQ 37"}
- {"dt": "2024-12-02T13:02:57.243055", "lvl": "target", "msg": "[    1.958652] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200"}
- {"dt": "2024-12-02T13:02:57.272247", "lvl": "target", "msg": "[    1.967735] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200"}
- {"dt": "2024-12-02T13:02:57.272822", "lvl": "target", "msg": "[    1.976996] rp1 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-02T13:02:57.275371", "lvl": "target", "msg": "[    1.983286] rp1 0000:01:00.0: chip_id 0x20001927"}
- {"dt": "2024-12-02T13:02:57.324484", "lvl": "target", "msg": "[    2.006563] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 105 (d8:3a:dd:e5:72:37)"}
- {"dt": "2024-12-02T13:02:57.325051", "lvl": "target", "msg": "[    2.019541] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels"}
- {"dt": "2024-12-02T13:02:57.325367", "lvl": "target", "msg": "[    2.028037] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.325637", "lvl": "target", "msg": "[    2.033108] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-02T13:02:57.327747", "lvl": "target", "msg": "[    2.040783] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-02T13:02:57.367912", "lvl": "target", "msg": "[    2.049788] xhci-hcd xhci-hcd.0: irq 130, io mem 0x1f00200000"}
- {"dt": "2024-12-02T13:02:57.368439", "lvl": "target", "msg": "[    2.055607] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.368779", "lvl": "target", "msg": "[    2.060675] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-02T13:02:57.369060", "lvl": "target", "msg": "[    2.067923] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-02T13:02:57.369315", "lvl": "target", "msg": "[    2.074068] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-02T13:02:57.369565", "lvl": "target", "msg": "[    2.082366] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T13:02:57.371075", "lvl": "target", "msg": "[    2.089615] usb usb1: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.411446", "lvl": "target", "msg": "[    2.094506] usb usb1: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T13:02:57.411974", "lvl": "target", "msg": "[    2.100532] usb usb1: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-02T13:02:57.412282", "lvl": "target", "msg": "[    2.105065] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-02T13:02:57.412550", "lvl": "target", "msg": "[    2.108832] hub 1-0:1.0: 2 ports detected"}
- {"dt": "2024-12-02T13:02:57.412838", "lvl": "target", "msg": "[    2.112935] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-02T13:02:57.413093", "lvl": "target", "msg": "[    2.121232] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T13:02:57.413339", "lvl": "target", "msg": "[    2.128481] usb usb2: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.414623", "lvl": "target", "msg": "[    2.133377] usb usb2: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T13:02:57.455817", "lvl": "target", "msg": "[    2.139412] usb usb2: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-02T13:02:57.456342", "lvl": "target", "msg": "[    2.143987] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-02T13:02:57.456647", "lvl": "target", "msg": "[    2.147756] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-02T13:02:57.456950", "lvl": "target", "msg": "[    2.151905] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.457205", "lvl": "target", "msg": "[    2.157080] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-02T13:02:57.457452", "lvl": "target", "msg": "[    2.164754] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-02T13:02:57.457695", "lvl": "target", "msg": "[    2.173849] xhci-hcd xhci-hcd.1: irq 135, io mem 0x1f00300000"}
- {"dt": "2024-12-02T13:02:57.499444", "lvl": "target", "msg": "[    2.179671] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.499967", "lvl": "target", "msg": "[    2.184737] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-02T13:02:57.500275", "lvl": "target", "msg": "[    2.192082] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-02T13:02:57.500545", "lvl": "target", "msg": "[    2.198219] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-02T13:02:57.500826", "lvl": "target", "msg": "[    2.206609] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T13:02:57.501077", "lvl": "target", "msg": "[    2.213857] usb usb3: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.502639", "lvl": "target", "msg": "[    2.218748] usb usb3: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T13:02:57.543269", "lvl": "target", "msg": "[    2.224868] usb usb3: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-02T13:02:57.543799", "lvl": "target", "msg": "[    2.229385] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-02T13:02:57.544108", "lvl": "target", "msg": "[    2.233152] hub 3-0:1.0: 2 ports detected"}
- {"dt": "2024-12-02T13:02:57.544377", "lvl": "target", "msg": "[    2.237252] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-02T13:02:57.544631", "lvl": "target", "msg": "[    2.245644] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T13:02:57.544913", "lvl": "target", "msg": "[    2.252903] usb usb4: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T13:02:57.545160", "lvl": "target", "msg": "[    2.257812] usb usb4: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T13:02:57.546431", "lvl": "target", "msg": "[    2.263945] usb usb4: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-02T13:02:57.587824", "lvl": "target", "msg": "[    2.268512] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-02T13:02:57.588348", "lvl": "target", "msg": "[    2.272277] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-02T13:02:57.588654", "lvl": "target", "msg": "[    2.276758] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T13:02:57.588961", "lvl": "target", "msg": "[    2.285101] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.589220", "lvl": "target", "msg": "[    2.293231] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.589469", "lvl": "target", "msg": "[    2.301378] platform 1000800000.codec: Adding to iommu group 0"}
- {"dt": "2024-12-02T13:02:57.590975", "lvl": "target", "msg": "[    2.307235] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.631832", "lvl": "target", "msg": "[    2.315538] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.632357", "lvl": "target", "msg": "[    2.323848] platform 1000880000.pisp_be: Adding to iommu group 0"}
- {"dt": "2024-12-02T13:02:57.632666", "lvl": "target", "msg": "[    2.329983] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.632982", "lvl": "target", "msg": "[    2.337936] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:02:57.633239", "lvl": "target", "msg": "[    2.346069] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T13:02:57.686394", "lvl": "target", "msg": "[    2.353125] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T13:02:57.686924", "lvl": "target", "msg": "[    2.361465] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T13:02:57.687236", "lvl": "target", "msg": "[    2.368466] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T13:02:57.687509", "lvl": "target", "msg": "[    2.376928] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T13:02:57.687766", "lvl": "target", "msg": "[    2.383990] mmc1: CQHCI version 5.10"}
- {"dt": "2024-12-02T13:02:57.688016", "lvl": "target", "msg": "[    2.387761] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO"}
- {"dt": "2024-12-02T13:02:57.688260", "lvl": "target", "msg": "[    2.388051] of_cfs_init"}
- {"dt": "2024-12-02T13:02:57.688499", "lvl": "target", "msg": "[    2.393113] mmc0: CQHCI version 5.10"}
- {"dt": "2024-12-02T13:02:57.689605", "lvl": "target", "msg": "[    2.395300] of_cfs_init: OK"}
- {"dt": "2024-12-02T13:02:57.728402", "lvl": "target", "msg": "[    2.433208] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-02T13:02:57.900636", "lvl": "target", "msg": "[    2.575169] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-02T13:02:57.901157", "lvl": "target", "msg": "[    2.591650] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)"}
- {"dt": "2024-12-02T13:02:57.901473", "lvl": "target", "msg": "[    2.602567] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode"}
- {"dt": "2024-12-02T13:02:57.901746", "lvl": "target", "msg": "[    2.612727] pps pps0: new PPS source ptp0"}
- {"dt": "2024-12-02T13:02:57.902730", "lvl": "target", "msg": "[    2.615297] mmc1: new ultra high speed DDR50 SDIO card at address 0001"}
- {"dt": "2024-12-02T13:02:57.919071", "lvl": "target", "msg": "[    2.616771] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered."}
- {"dt": "2024-12-02T13:03:00.980833", "lvl": "target", "msg": "[    5.685650] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-02T13:03:01.039421", "lvl": "target", "msg": "[    5.708636] Sending DHCP requests ., OK"}
- {"dt": "2024-12-02T13:03:01.039908", "lvl": "target", "msg": "[    5.728485] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.231"}
- {"dt": "2024-12-02T13:03:01.040207", "lvl": "target", "msg": "[    5.736434] IP-Config: Complete:"}
- {"dt": "2024-12-02T13:03:01.040470", "lvl": "target", "msg": "[    5.739668]      device=eth0, hwaddr=d8:3a:dd:e5:72:37, ipaddr=192.168.66.231, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-12-02T13:03:01.040721", "lvl": "target", "msg": "[    5.750061]      host=192.168.66.231, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-02T13:03:01.042785", "lvl": "target", "msg": "[    5.757047]      bootserver=192.168.66.1, rootserver=192.168.66.1, rootpath="}
- {"dt": "2024-12-02T13:03:01.043184", "lvl": "target", "msg": "[    5.757048]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-02T13:03:01.079220", "lvl": "target", "msg": "[    5.767873] clk: Disabling unused clocks"}
- {"dt": "2024-12-02T13:03:01.079748", "lvl": "target", "msg": "[    5.780327] ALSA device list:"}
- {"dt": "2024-12-02T13:03:01.080044", "lvl": "target", "msg": "[    5.783314]   No soundcards found."}
- {"dt": "2024-12-02T13:03:01.080309", "lvl": "target", "msg": "[    5.786756] uart-pl011 107d001000.serial: no DMA platform data"}
- {"dt": "2024-12-02T13:03:01.082486", "lvl": "target", "msg": "[    5.792616] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-12-02T13:03:08.140225", "lvl": "target", "msg": "[   12.852665] RAMDISK: ext2 filesystem found at block 0"}
- {"dt": "2024-12-02T13:03:08.272313", "lvl": "target", "msg": "[   12.857733] 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-12-02T13:03:08.329205", "lvl": "target", "msg": "[   12.865731] |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\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-12-02T13:03:08.329791", "lvl": "target", "msg": "[   13.020708] EXT4-fs (ram0): mounted filesystem d291e3c8-a47c-4bba-b248-a917c9104949 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-02T13:03:08.330105", "lvl": "target", "msg": "[   13.032440] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-12-02T13:03:08.330378", "lvl": "target", "msg": "[   13.038319] devtmpfs: mounted"}
- {"dt": "2024-12-02T13:03:08.330632", "lvl": "target", "msg": "[   13.043658] Freeing unused kernel memory: 5568K"}
- {"dt": "2024-12-02T13:03:08.332700", "lvl": "target", "msg": "[   13.048237] Run /sbin/init as init process"}
- {"dt": "2024-12-02T13:03:08.372374", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-02T13:03:08.372960", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-12-02T13:03:08.373275", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-12-02T13:03:08.373548", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-12-02T13:03:08.373802", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-12-02T13:03:08.374050", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-12-02T13:03:08.415707", "lvl": "target", "msg": "__________________________________________[   13.096892] nbd0: detected capacity change from 0 to 8962048"}
- {"dt": "2024-12-02T13:03:08.416211", "lvl": "target", "msg": "______________________________________"}
- {"dt": "2024-12-02T13:03:08.416517", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2024-12-02T13:03:08.416845", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-12-02T13:03:08.417105", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-12-02T13:03:08.417348", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-12-02T13:03:08.417588", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-12-02T13:03:08.417829", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-12-02T13:03:08.418066", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-12-02T13:03:08.418300", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-12-02T13:03:08.419248", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-12-02T13:03:08.442307", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.68.1:61986"}
- {"dt": "2024-12-02T13:03:08.442854", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-12-02T13:03:08.445574", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-12-02T13:03:09.305029", "lvl": "target", "msg": "[   13.986457] EXT4-fs (nbd0): mounted filesystem 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-02T13:03:09.305215", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2024-12-02T13:03:09.305323", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2024-12-02T13:03:09.305409", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2024-12-02T13:03:09.305489", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2024-12-02T13:03:09.305571", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2024-12-02T13:03:09.308240", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2024-12-02T13:03:09.650583", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2024-12-02T13:03:09.653890", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0,enp2s0,enp3s0,end0,enp0s2,enp0s1 '"}
- {"dt": "2024-12-02T13:03:09.749464", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2024-12-02T13:03:09.853520", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2024-12-02T13:03:09.854096", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-02T13:03:09.854547", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2024-12-02T13:03:09.854909", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2024-12-02T13:03:09.855324", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2024-12-02T13:03:09.856564", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2024-12-02T13:03:09.857098", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2024-12-02T13:03:09.872244", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2024-12-02T13:03:10.495071", "lvl": "target", "msg": "[   15.199605] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2024-12-02T13:03:10.651699", "lvl": "target", "msg": "[   15.353746] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-12-02T13:03:10.652368", "lvl": "target", "msg": "[   15.359088] Segment Routing with IPv6"}
- {"dt": "2024-12-02T13:03:10.654877", "lvl": "target", "msg": "[   15.362765] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-12-02T13:03:10.796324", "lvl": "target", "msg": "[   15.475223] 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-12-02T13:03:10.798803", "lvl": "target", "msg": "[   15.507225] systemd[1]: Detected architecture arm64."}
- {"dt": "2024-12-02T13:03:10.814624", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:10.815270", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 18.0.2 (ricefish)!"}
- {"dt": "2024-12-02T13:03:10.815664", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:10.830435", "lvl": "target", "msg": "[   15.548474] systemd[1]: Hostname set to <raspberrypi5>."}
- {"dt": "2024-12-02T13:03:10.873294", "lvl": "target", "msg": "[   15.577844] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2024-12-02T13:03:11.580885", "lvl": "target", "msg": "[   16.284800] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2024-12-02T13:03:11.678602", "lvl": "target", "msg": "[   16.325257] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2024-12-02T13:03:11.679101", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2024-12-02T13:03:11.679399", "lvl": "target", "msg": "[   16.344912] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2024-12-02T13:03:11.679665", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2024-12-02T13:03:11.679913", "lvl": "target", "msg": "[   16.364858] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-02T13:03:11.680901", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-02T13:03:11.681216", "lvl": "target", "msg": "[   16.384847] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-02T13:03:11.682175", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-02T13:03:11.743663", "lvl": "target", "msg": "[   16.404762] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2024-12-02T13:03:11.744207", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2024-12-02T13:03:11.744505", "lvl": "target", "msg": "[   16.420703] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-02T13:03:11.744813", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-02T13:03:11.745809", "lvl": "target", "msg": "[   16.444681] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-02T13:03:11.746084", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-02T13:03:11.747045", "lvl": "target", "msg": "[   16.464659] systemd[1]: Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-02T13:03:11.816107", "lvl": "target", "msg": "         Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-02T13:03:11.816646", "lvl": "target", "msg": "[   16.484671] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-02T13:03:11.816988", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-02T13:03:11.817254", "lvl": "target", "msg": "[   16.504650] systemd[1]: Reached target Path Units."}
- {"dt": "2024-12-02T13:03:11.817509", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2024-12-02T13:03:11.817751", "lvl": "target", "msg": "[   16.520660] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2024-12-02T13:03:11.817988", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2024-12-02T13:03:11.819265", "lvl": "target", "msg": "[   16.536653] systemd[1]: Reached target Slice Units."}
- {"dt": "2024-12-02T13:03:11.844560", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2024-12-02T13:03:11.845122", "lvl": "target", "msg": "[   16.552659] systemd[1]: Reached target Swaps."}
- {"dt": "2024-12-02T13:03:11.847969", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2024-12-02T13:03:11.919866", "lvl": "target", "msg": "[   16.571197] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2024-12-02T13:03:11.920397", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2024-12-02T13:03:11.920694", "lvl": "target", "msg": "[   16.588705] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-02T13:03:11.921004", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-02T13:03:11.921253", "lvl": "target", "msg": "[   16.608822] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2024-12-02T13:03:11.921495", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2024-12-02T13:03:11.921734", "lvl": "target", "msg": "[   16.624745] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-02T13:03:11.923019", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-02T13:03:11.992888", "lvl": "target", "msg": "[   16.644744] systemd[1]: Listening on Journal Socket."}
- {"dt": "2024-12-02T13:03:11.993439", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2024-12-02T13:03:11.993745", "lvl": "target", "msg": "[   16.660769] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-02T13:03:11.994016", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-02T13:03:11.994268", "lvl": "target", "msg": "[   16.680761] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2024-12-02T13:03:11.994512", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2024-12-02T13:03:11.994755", "lvl": "target", "msg": "[   16.696721] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2024-12-02T13:03:11.994994", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2024-12-02T13:03:11.995973", "lvl": "target", "msg": "[   16.712728] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2024-12-02T13:03:12.028040", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2024-12-02T13:03:12.031200", "lvl": "target", "msg": "[   16.732744] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages)."}
- {"dt": "2024-12-02T13:03:12.085205", "lvl": "target", "msg": "[   16.772736] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-02T13:03:12.085717", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-02T13:03:12.086030", "lvl": "target", "msg": "[   16.793258] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2024-12-02T13:03:12.088608", "lvl": "target", "msg": "         Mounting Kernel Debug File System..."}
- {"dt": "2024-12-02T13:03:12.106016", "lvl": "target", "msg": "[   16.813248] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2024-12-02T13:03:12.109120", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2024-12-02T13:03:12.158033", "lvl": "target", "msg": "[   16.834717] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2024-12-02T13:03:12.158550", "lvl": "target", "msg": "         Mounting Temporary Direc[   16.841342] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2024-12-02T13:03:12.158858", "lvl": "target", "msg": "tory /tmp..."}
- {"dt": "2024-12-02T13:03:12.159129", "lvl": "target", "msg": "         Starting Create List of [   16.861619] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2024-12-02T13:03:12.159384", "lvl": "target", "msg": "Static Device Nodes..."}
- {"dt": "2024-12-02T13:03:12.161155", "lvl": "target", "msg": "         Starting Load Kernel Module configfs..."}
- {"dt": "2024-12-02T13:03:12.185823", "lvl": "target", "msg": "[   16.893507] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2024-12-02T13:03:12.188955", "lvl": "target", "msg": "         Starting Load Kernel Module drm..."}
- {"dt": "2024-12-02T13:03:12.206532", "lvl": "target", "msg": "[   16.913448] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2024-12-02T13:03:12.209699", "lvl": "target", "msg": "         Starting Load Kernel Module fuse..."}
- {"dt": "2024-12-02T13:03:12.267016", "lvl": "target", "msg": "[   16.933682] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-02T13:03:12.267538", "lvl": "target", "msg": "         Starting SELinux autorel[   16.940755] fuse: init (API version 7.39)"}
- {"dt": "2024-12-02T13:03:12.267852", "lvl": "target", "msg": "abel service loading..."}
- {"dt": "2024-12-02T13:03:12.268122", "lvl": "target", "msg": "[   16.957586] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-02T13:03:12.268379", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-02T13:03:12.269340", "lvl": "target", "msg": "[   16.976725] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2024-12-02T13:03:12.270356", "lvl": "target", "msg": "[   16.990560] systemd[1]: Starting Journal Service..."}
- {"dt": "2024-12-02T13:03:12.285444", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2024-12-02T13:03:12.391640", "lvl": "target", "msg": "[   17.060870] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2024-12-02T13:03:12.392185", "lvl": "target", "msg": "         Starting Load Kernel Modules..."}
- {"dt": "2024-12-02T13:03:12.392549", "lvl": "target", "msg": "[   17.077579] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-12-02T13:03:12.392920", "lvl": "target", "msg": "         Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-12-02T13:03:12.393233", "lvl": "target", "msg": "[   17.098108] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2024-12-02T13:03:12.393501", "lvl": "target", "msg": "         Starting Coldplug All ud[   17.104356] systemd-journald[197]: Collecting audit messages is enabled."}
- {"dt": "2024-12-02T13:03:12.393757", "lvl": "target", "msg": "ev Devices..."}
- {"dt": "2024-12-02T13:03:12.439059", "lvl": "target", "msg": "[   17.105818] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-02T13:03:12.439533", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-02T13:03:12.439842", "lvl": "target", "msg": "[   17.133116] systemd[1]: Mounted Kernel Debug File System."}
- {"dt": "2024-12-02T13:03:12.440119", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2024-12-02T13:03:12.442246", "lvl": "target", "msg": "[   17.144803] EXT4-fs (nbd0): re-mounted 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w. Quota mode: none."}
- {"dt": "2024-12-02T13:03:12.481968", "lvl": "target", "msg": "[   17.165276] systemd[1]: Mounted Kernel Trace File System."}
- {"dt": "2024-12-02T13:03:12.482433", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2024-12-02T13:03:12.482742", "lvl": "target", "msg": "[   17.180048] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-02T13:03:12.485153", "lvl": "target", "msg": "[   17.190261] snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored"}
- {"dt": "2024-12-02T13:03:12.502876", "lvl": "target", "msg": "[   17.208872] systemd[1]: Started Journal Service."}
- {"dt": "2024-12-02T13:03:12.506069", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2024-12-02T13:03:12.547111", "lvl": "target", "msg": "[   17.238527] audit: type=1130 audit(1725908515.035:2): 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-12-02T13:03:12.550476", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-02T13:03:12.566279", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-02T13:03:12.616250", "lvl": "target", "msg": "[   17.292757] audit: type=1130 audit(1725908515.091:3): 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-12-02T13:03:12.616801", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2024-12-02T13:03:12.619680", "lvl": "target", "msg": "[   17.324703] audit: type=1130 audit(1725908515.123: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-12-02T13:03:12.653780", "lvl": "target", "msg": "[   17.343652] audit: type=1131 audit(1725908515.123: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-12-02T13:03:12.654297", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2024-12-02T13:03:12.703984", "lvl": "target", "msg": "[   17.380961] audit: type=1130 audit(1725908515.179: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-12-02T13:03:12.704514", "lvl": "target", "msg": "[   17.399489] audit: type=1131 audit(1725908515.179: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-12-02T13:03:12.707418", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2024-12-02T13:03:12.757134", "lvl": "target", "msg": "[   17.428672] audit: type=1130 audit(1725908515.227: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-12-02T13:03:12.757667", "lvl": "target", "msg": "[   17.447281] audit: type=1131 audit(1725908515.227: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-12-02T13:03:12.757985", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-02T13:03:12.810556", "lvl": "target", "msg": "[   17.480668] audit: type=1130 audit(1725908515.279: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-12-02T13:03:12.811093", "lvl": "target", "msg": "[  OK  ] Finished SELinux init fo[   17.499889] audit: type=1131 audit(1725908515.279: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-12-02T13:03:12.811437", "lvl": "target", "msg": "r /dev service loading."}
- {"dt": "2024-12-02T13:03:12.813707", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2024-12-02T13:03:12.829624", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2024-12-02T13:03:12.845634", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2024-12-02T13:03:12.877569", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2024-12-02T13:03:12.893497", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2024-12-02T13:03:12.909382", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2024-12-02T13:03:12.963966", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2024-12-02T13:03:12.964496", "lvl": "target", "msg": "[   17.648199] systemd-journald[197]: Received client request to flush runtime journal."}
- {"dt": "2024-12-02T13:03:12.964855", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev gracefully..."}
- {"dt": "2024-12-02T13:03:12.965145", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2024-12-02T13:03:12.967097", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2024-12-02T13:03:12.982908", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2024-12-02T13:03:13.009048", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2024-12-02T13:03:13.012109", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev gracefully."}
- {"dt": "2024-12-02T13:03:13.043917", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2024-12-02T13:03:13.139804", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2024-12-02T13:03:13.155501", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2024-12-02T13:03:13.171742", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2024-12-02T13:03:13.219831", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2024-12-02T13:03:13.235781", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2024-12-02T13:03:13.253551", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2024-12-02T13:03:13.256939", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2024-12-02T13:03:13.288907", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2024-12-02T13:03:13.289421", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2024-12-02T13:03:13.319286", "lvl": "target", "msg": "         Starting Load/Save OS Random Seed..."}
- {"dt": "2024-12-02T13:03:13.319801", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2024-12-02T13:03:13.336371", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2024-12-02T13:03:13.352136", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-02T13:03:13.377069", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-02T13:03:13.379328", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2024-12-02T13:03:13.405946", "lvl": "target", "msg": "[  OK  ] Finished Load/Save OS Random Seed."}
- {"dt": "2024-12-02T13:03:13.409069", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-02T13:03:13.441008", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2024-12-02T13:03:13.456989", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-02T13:03:13.504796", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2024-12-02T13:03:13.547576", "lvl": "target", "msg": "         Starting Load Audit Rules..."}
- {"dt": "2024-12-02T13:03:13.550805", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2024-12-02T13:03:13.742443", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2024-12-02T13:03:13.838148", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2024-12-02T13:03:13.918036", "lvl": "target", "msg": "[  OK  ] Finished Load Audit Rules."}
- {"dt": "2024-12-02T13:03:13.965963", "lvl": "target", "msg": "         Starting Security Audit Logging Service..."}
- {"dt": "2024-12-02T13:03:13.995893", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2024-12-02T13:03:14.107739", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-12-02T13:03:14.184534", "lvl": "target", "msg": "[   18.889319] input: pwr_button as /devices/platform/pwr_button/input/input0"}
- {"dt": "2024-12-02T13:03:14.227863", "lvl": "target", "msg": "[   18.912125] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040"}
- {"dt": "2024-12-02T13:03:14.228342", "lvl": "target", "msg": "[   18.920342] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1"}
- {"dt": "2024-12-02T13:03:14.228656", "lvl": "target", "msg": "[   18.928291] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040"}
- {"dt": "2024-12-02T13:03:14.228957", "lvl": "target", "msg": "[   18.936251] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2"}
- {"dt": "2024-12-02T13:03:14.229215", "lvl": "target", "msg": "[   18.943997] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020"}
- {"dt": "2024-12-02T13:03:14.292257", "lvl": "target", "msg": "[   18.951865] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3"}
- {"dt": "2024-12-02T13:03:14.292732", "lvl": "target", "msg": "[   18.959506] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020"}
- {"dt": "2024-12-02T13:03:14.293089", "lvl": "target", "msg": "[   18.969664] macb 1f00100000.ethernet end0: renamed from eth0 (while UP)"}
- {"dt": "2024-12-02T13:03:14.293364", "lvl": "target", "msg": "[   18.980707] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4"}
- {"dt": "2024-12-02T13:03:14.293622", "lvl": "target", "msg": "[   18.990577] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000"}
- {"dt": "2024-12-02T13:03:14.295458", "lvl": "target", "msg": "[   19.000438] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0"}
- {"dt": "2024-12-02T13:03:14.327219", "lvl": "target", "msg": "[  OK  ] Started Security Audit Logging Service."}
- {"dt": "2024-12-02T13:03:14.343274", "lvl": "target", "msg": "[   19.059261] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-02T13:03:14.359167", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2024-12-02T13:03:14.438980", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2024-12-02T13:03:14.470969", "lvl": "target", "msg": "[   19.177272] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-02T13:03:14.531803", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2024-12-02T13:03:14.605811", "lvl": "target", "msg": "[   19.291012] Bluetooth: Core ver 2.22"}
- {"dt": "2024-12-02T13:03:14.606281", "lvl": "target", "msg": "[   19.294673] NET: Registered PF_BLUETOOTH protocol family"}
- {"dt": "2024-12-02T13:03:14.606601", "lvl": "target", "msg": "[   19.300103] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-12-02T13:03:14.606878", "lvl": "target", "msg": "[   19.306492] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-12-02T13:03:14.607136", "lvl": "target", "msg": "[   19.311483] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-12-02T13:03:14.607382", "lvl": "target", "msg": "[   19.312425] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-02T13:03:14.609100", "lvl": "target", "msg": "[   19.316780] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-12-02T13:03:14.649443", "lvl": "target", "msg": "[   19.326877] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:03:14.649921", "lvl": "target", "msg": "[   19.339267] pispbe 1000880000.pisp_be: pispbe_probe: HW version:  0x02252700"}
- {"dt": "2024-12-02T13:03:14.650236", "lvl": "target", "msg": "[   19.341866] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-12-02T13:03:14.650504", "lvl": "target", "msg": "[   19.342667] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T13:03:14.650755", "lvl": "target", "msg": "[   19.346351] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000"}
- {"dt": "2024-12-02T13:03:14.652637", "lvl": "target", "msg": "[   19.346353] pispbe 1000880000.pisp_be: pispbe_probe: Status:      0x00000000"}
- {"dt": "2024-12-02T13:03:14.693078", "lvl": "target", "msg": "[   19.354185] rpivid 1000800000.codec: Device registered as /dev/video19"}
- {"dt": "2024-12-02T13:03:14.693565", "lvl": "target", "msg": "[   19.356760] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'"}
- {"dt": "2024-12-02T13:03:14.693937", "lvl": "target", "msg": "[   19.356960] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'"}
- {"dt": "2024-12-02T13:03:14.694263", "lvl": "target", "msg": "[   19.357151] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-12-02T13:03:14.694554", "lvl": "target", "msg": "[   19.357340] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2024-12-02T13:03:14.696228", "lvl": "target", "msg": "[   19.361649] pispbe 1000880000.pisp_be: Register nodes for group 0"}
- {"dt": "2024-12-02T13:03:14.736374", "lvl": "target", "msg": "[   19.362610] pispbe 1000880000.pisp_be: input device node registered as /dev/video20"}
- {"dt": "2024-12-02T13:03:14.736863", "lvl": "target", "msg": "[   19.380710] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-12-02T13:03:14.737185", "lvl": "target", "msg": "[   19.390741] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21"}
- {"dt": "2024-12-02T13:03:14.737461", "lvl": "target", "msg": "[   19.399703] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-12-02T13:03:14.737712", "lvl": "target", "msg": "[   19.399898] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-12-02T13:03:14.737956", "lvl": "target", "msg": "[   19.408722] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22"}
- {"dt": "2024-12-02T13:03:14.779751", "lvl": "target", "msg": "[   19.412666] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-12-02T13:03:14.780218", "lvl": "target", "msg": "[   19.412669] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2024-12-02T13:03:14.780526", "lvl": "target", "msg": "[   19.412691] Bluetooth: HCI UART protocol Three-wire (H5) registered"}
- {"dt": "2024-12-02T13:03:14.780833", "lvl": "target", "msg": "[   19.418820] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23"}
- {"dt": "2024-12-02T13:03:14.781105", "lvl": "target", "msg": "[   19.426591] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator"}
- {"dt": "2024-12-02T13:03:14.781361", "lvl": "target", "msg": "[   19.435159] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24"}
- {"dt": "2024-12-02T13:03:14.782990", "lvl": "target", "msg": "[   19.443190] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-12-02T13:03:14.823129", "lvl": "target", "msg": "[   19.448032] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25"}
- {"dt": "2024-12-02T13:03:14.823801", "lvl": "target", "msg": "[   19.448286] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator"}
- {"dt": "2024-12-02T13:03:14.824298", "lvl": "target", "msg": "[   19.465906] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26"}
- {"dt": "2024-12-02T13:03:14.824808", "lvl": "target", "msg": "[   19.472377] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-12-02T13:03:14.825261", "lvl": "target", "msg": "[   19.477552] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27"}
- {"dt": "2024-12-02T13:03:14.866535", "lvl": "target", "msg": "[   19.485779] usbcore: registered new interface driver brcmfmac"}
- {"dt": "2024-12-02T13:03:14.866997", "lvl": "target", "msg": "[   19.493090] pispbe 1000880000.pisp_be: config device node registered as /dev/video28"}
- {"dt": "2024-12-02T13:03:14.867309", "lvl": "target", "msg": "[   19.523858] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2"}
- {"dt": "2024-12-02T13:03:14.867583", "lvl": "target", "msg": "[   19.530270] pispbe 1000880000.pisp_be: Register nodes for group 1"}
- {"dt": "2024-12-02T13:03:14.867837", "lvl": "target", "msg": "[   19.578513] pispbe 1000880000.pisp_be: input device node registered as /dev/video29"}
- {"dt": "2024-12-02T13:03:14.869740", "lvl": "target", "msg": "[   19.586328] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30"}
- {"dt": "2024-12-02T13:03:14.910157", "lvl": "target", "msg": "[   19.594400] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31"}
- {"dt": "2024-12-02T13:03:14.910629", "lvl": "target", "msg": "[   19.602813] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32"}
- {"dt": "2024-12-02T13:03:14.910960", "lvl": "target", "msg": "[   19.610965] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33"}
- {"dt": "2024-12-02T13:03:14.911262", "lvl": "target", "msg": "[   19.618939] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34"}
- {"dt": "2024-12-02T13:03:14.913403", "lvl": "target", "msg": "[   19.626824] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35"}
- {"dt": "2024-12-02T13:03:14.936894", "lvl": "target", "msg": "[   19.634981] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36"}
- {"dt": "2024-12-02T13:03:14.940095", "lvl": "target", "msg": "[   19.643410] pispbe 1000880000.pisp_be: config device node registered as /dev/video37"}
- {"dt": "2024-12-02T13:03:14.940610", "lvl": "target", "msg": "[  OK  ] Finished Virtual Console Setup."}
- {"dt": "2024-12-02T13:03:15.003833", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-02T13:03:15.035883", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-02T13:03:15.051837", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/bthelper."}
- {"dt": "2024-12-02T13:03:15.114330", "lvl": "target", "msg": "[   19.791826] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)"}
- {"dt": "2024-12-02T13:03:15.114831", "lvl": "target", "msg": "[   19.799720] 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-12-02T13:03:15.115140", "lvl": "target", "msg": "         Starting SELinux resolv.[   19.813039] Bluetooth: hci0: BCM: chip id 107"}
- {"dt": "2024-12-02T13:03:15.115411", "lvl": "target", "msg": "conf relabeling..."}
- {"dt": "2024-12-02T13:03:15.115661", "lvl": "target", "msg": "[   19.819641] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-02T13:03:15.115908", "lvl": "target", "msg": "[   19.826878] Bluetooth: hci0: BCM4345C0"}
- {"dt": "2024-12-02T13:03:15.117538", "lvl": "target", "msg": "[   19.830672] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000"}
- {"dt": "2024-12-02T13:03:15.149450", "lvl": "target", "msg": "         Starting SELinux autorelabel service loa[   19.843562] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch"}
- {"dt": "2024-12-02T13:03:15.150131", "lvl": "target", "msg": "ding..."}
- {"dt": "2024-12-02T13:03:15.152619", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-02T13:03:15.168582", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-02T13:03:15.231230", "lvl": "target", "msg": "         Starting Network Name Resolution..."}
- {"dt": "2024-12-02T13:03:15.231777", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-02T13:03:15.232164", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-02T13:03:15.232497", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-02T13:03:15.234379", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-02T13:03:15.362104", "lvl": "target", "msg": "[  OK  ] Started Network Name Resolution."}
- {"dt": "2024-12-02T13:03:15.835058", "lvl": "target", "msg": "[   20.525220] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-02T13:03:15.835746", "lvl": "target", "msg": "[   20.531086] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190"}
- {"dt": "2024-12-02T13:03:15.836183", "lvl": "target", "msg": "[   20.537291] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382"}
- {"dt": "2024-12-02T13:03:15.838205", "lvl": "target", "msg": "[   20.543557] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)"}
- {"dt": "2024-12-02T13:03:17.642316", "lvl": "target", "msg": "[*     ] (1 of 2) A start job is running for\u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2024-12-02T13:03:18.225138", "lvl": "target", "msg": "M[K[**    ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-02T13:03:18.808908", "lvl": "target", "msg": "M[K[***   ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-02T13:03:19.391725", "lvl": "target", "msg": "M[K[ ***  ] (2 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (7s / 1min 30s)"}
- {"dt": "2024-12-02T13:03:19.902891", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2024-12-02T13:03:19.950923", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2024-12-02T13:03:20.002596", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2024-12-02T13:03:20.003154", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2024-12-02T13:03:20.003463", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2024-12-02T13:03:20.003735", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2024-12-02T13:03:20.005797", "lvl": "target", "msg": "[  OK  ] Listening on agl-compositor, a Wayl\u2026ositor, as a systemd user service."}
- {"dt": "2024-12-02T13:03:20.043928", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2024-12-02T13:03:20.044510", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2024-12-02T13:03:20.080880", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2024-12-02T13:03:20.081435", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2024-12-02T13:03:20.108830", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Manager Socket..."}
- {"dt": "2024-12-02T13:03:20.133189", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2024-12-02T13:03:20.136273", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2024-12-02T13:03:20.154268", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Manager Socket."}
- {"dt": "2024-12-02T13:03:20.157587", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2024-12-02T13:03:20.173365", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2024-12-02T13:03:20.189440", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2024-12-02T13:03:20.205093", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2024-12-02T13:03:20.237215", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2024-12-02T13:03:20.252909", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2024-12-02T13:03:20.269003", "lvl": "target", "msg": "         Starting Raspberry Pi bluetooth helper..."}
- {"dt": "2024-12-02T13:03:20.284843", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2024-12-02T13:03:20.340628", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2024-12-02T13:03:20.341141", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2024-12-02T13:03:20.341458", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2024-12-02T13:03:20.341732", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2024-12-02T13:03:20.359723", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2024-12-02T13:03:20.360423", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2024-12-02T13:03:20.385610", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (audio)."}
- {"dt": "2024-12-02T13:03:20.416486", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2024-12-02T13:03:20.419690", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2024-12-02T13:03:20.439774", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (video-capture)."}
- {"dt": "2024-12-02T13:03:20.465432", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2024-12-02T13:03:20.468629", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2024-12-02T13:03:20.496338", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2024-12-02T13:03:20.496981", "lvl": "target", "msg": "[   25.200196] CAN device driver interface"}
- {"dt": "2024-12-02T13:03:20.499648", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2024-12-02T13:03:20.500296", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2024-12-02T13:03:20.515032", "lvl": "target", "msg": "[  OK  ] Finished Raspberry Pi bluetooth helper."}
- {"dt": "2024-12-02T13:03:20.531275", "lvl": "target", "msg": "[   25.244584] vcan: Virtual CAN interface driver"}
- {"dt": "2024-12-02T13:03:20.579221", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2024-12-02T13:03:20.579790", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2024-12-02T13:03:20.627154", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2024-12-02T13:03:21.888699", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2024-12-02T13:03:22.144175", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2024-12-02T13:03:22.176028", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2024-12-02T13:03:22.191859", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2024-12-02T13:03:22.207917", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2024-12-02T13:03:22.223882", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2024-12-02T13:03:22.403392", "lvl": "target", "msg": "[   27.099062] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2024-12-02T13:03:22.403938", "lvl": "target", "msg": "[   27.104404] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2024-12-02T13:03:22.404246", "lvl": "target", "msg": "[   27.109652] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2024-12-02T13:03:22.406691", "lvl": "target", "msg": "[   27.115978] Bluetooth: MGMT ver 1.22"}
- {"dt": "2024-12-02T13:03:22.470322", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2024-12-02T13:03:22.566251", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2024-12-02T13:03:22.709788", "lvl": "target", "msg": "[   27.412300] Bluetooth: RFCOMM TTY layer initialized"}
- {"dt": "2024-12-02T13:03:22.710326", "lvl": "target", "msg": "[   27.417210] Bluetooth: RFCOMM socket layer initialized"}
- {"dt": "2024-12-02T13:03:22.713116", "lvl": "target", "msg": "[   27.422372] Bluetooth: RFCOMM ver 1.11"}
- {"dt": "2024-12-02T13:03:22.728845", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2024-12-02T13:03:22.744858", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2024-12-02T13:03:22.792825", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2024-12-02T13:03:22.813810", "lvl": "target", "msg": "[   27.518813] pipewire[475]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set"}
- {"dt": "2024-12-02T13:03:22.829710", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2024-12-02T13:03:22.877536", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val databroker."}
- {"dt": "2024-12-02T13:03:22.893582", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2024-12-02T13:03:22.909504", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2024-12-02T13:03:22.941386", "lvl": "target", "msg": "         Starting agl-vss-helper.service..."}
- {"dt": "2024-12-02T13:03:22.969259", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2024-12-02T13:03:22.972467", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2024-12-02T13:03:23.004320", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2024-12-02T13:03:23.052275", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2024-12-02T13:03:23.084279", "lvl": "target", "msg": "         Starting AGL compositor..."}
- {"dt": "2024-12-02T13:03:23.100152", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2024-12-02T13:03:23.119978", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttyAMA10."}
- {"dt": "2024-12-02T13:03:23.123177", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2024-12-02T13:03:23.650034", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2024-12-02T13:03:23.953483", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-02T13:03:23.969431", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-02T13:03:25.055093", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2024-12-02T13:03:25.358564", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2024-12-02T13:03:25.374455", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2024-12-02T13:03:25.534156", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2024-12-02T13:03:25.566055", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2024-12-02T13:03:25.598041", "lvl": "target", "msg": "[  OK  ] Finished OpenSSH Key Generation."}
- {"dt": "2024-12-02T13:03:25.725763", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2024-12-02T13:03:25.757698", "lvl": "target", "msg": "         Starting applaunchd.service..."}
- {"dt": "2024-12-02T13:03:25.782566", "lvl": "target", "msg": "[   30.490394] can: controller area network core"}
- {"dt": "2024-12-02T13:03:25.785781", "lvl": "target", "msg": "[   30.494805] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-12-02T13:03:25.801521", "lvl": "target", "msg": "[   30.520472] can: raw protocol"}
- {"dt": "2024-12-02T13:03:25.833542", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2024-12-02T13:03:25.865438", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2024-12-02T13:03:26.695884", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2024-12-02T13:03:26.711700", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2024-12-02T13:03:26.775617", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2024-12-02T13:03:27.047078", "lvl": "target", "msg": "[FAILED] Failed to start AGL compositor."}
- {"dt": "2024-12-02T13:03:27.084787", "lvl": "target", "msg": "See 'systemctl status agl-compositor.service' for details."}
- {"dt": "2024-12-02T13:03:27.085329", "lvl": "target", "msg": "[DEPEND] Dependency failed for homescreen.service."}
- {"dt": "2024-12-02T13:03:27.087990", "lvl": "target", "msg": "[DEPEND] Dependency failed for launcher.service."}
- {"dt": "2024-12-02T13:03:29.211622", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:29.229403", "lvl": "target", "msg": "Automotive Grade Linux 18.0.2 raspberrypi5 ttyAMA10"}
- {"dt": "2024-12-02T13:03:29.230075", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:29.233630", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2024-12-02T13:03:29.234149", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "33.91", "extra": {"extra": []}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:03:29.234848", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-12-02T13:03:29.235238", "lvl": "input", "msg": "root\n"}
- {"dt": "2024-12-02T13:03:29.335954", "lvl": "target", "msg": "raspberrypi5 login: root"}
- {"dt": "2024-12-02T13:03:29.336552", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T13:03:29.336946", "lvl": "debug", "msg": "login-action: Wait for prompt ['raspberrypi5:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:07:24)"}
- {"dt": "2024-12-02T13:03:29.344271", "lvl": "target", "msg": "root"}
- {"dt": "2024-12-02T13:03:32.650171", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T13:03:32.650838", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:00:37) [common]"}
- {"dt": "2024-12-02T13:03:32.651209", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "37.33", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:03:32.651821", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:01:47) [common]"}
- {"dt": "2024-12-02T13:03:32.652215", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:07:21) [common]"}
- {"dt": "2024-12-02T13:03:32.652551", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T13:03:32.652856", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T13:03:32.653143", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-02T13:03:32.703698", "lvl": "target", "msg": "7[r[999;999H[6nroot@raspberrypi5:~# "}
- {"dt": "2024-12-02T13:03:32.704520", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2024-12-02T13:03:32.705056", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2024-12-02T13:03:32.713643", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:32.730021", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:03:32.730666", "lvl": "debug", "msg": "start: 2.5 overlay-unpack (timeout 00:07:21) [common]"}
- {"dt": "2024-12-02T13:03:32.731101", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T13:03:32.831920", "lvl": "target", "msg": "root@raspberrypi5:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:32.841434", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:32.885420", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2024-12-02T13:03:32.886296", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/38059/compress-overlay-u6lbnvxh/overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T13:03:32.987278", "lvl": "target", "msg": "root@raspberrypi5:~# wget http://192.168.66.1/tmp/38059/compress-overlay-u6lbnvxh/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:32.996173", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/38059/compress-overlay-u6lbnvxhh/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:33.037243", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2024-12-02T13:03:33.037765", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2024-12-02T13:03:33.038068", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  8034  0:00:00 ETA"}
- {"dt": "2024-12-02T13:03:33.038344", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2024-12-02T13:03:33.053205", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T13:03:33.154426", "lvl": "target", "msg": "root@raspberrypi5:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:33.164810", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T13:03:33.228257", "lvl": "target", "msg": "x lava-38059/"}
- {"dt": "2024-12-02T13:03:33.228828", "lvl": "target", "msg": "x lava-38059/0/"}
- {"dt": "2024-12-02T13:03:33.268594", "lvl": "target", "msg": "x lava-38059/0/lava-test-runner.conf"}
- {"dt": "2024-12-02T13:03:33.269173", "lvl": "target", "msg": "x lava-38059/0/tests/"}
- {"dt": "2024-12-02T13:03:33.269484", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/"}
- {"dt": "2024-12-02T13:03:33.269757", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/inline/"}
- {"dt": "2024-12-02T13:03:33.270010", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/inline/dmesg.yaml"}
- {"dt": "2024-12-02T13:03:33.270254", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/run.sh"}
- {"dt": "2024-12-02T13:03:33.270495", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/testdef.yaml"}
- {"dt": "2024-12-02T13:03:33.270734", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/testdef_metadata"}
- {"dt": "2024-12-02T13:03:33.270972", "lvl": "target", "msg": "x lava-38059/0/tests/0_dmesg/uuid"}
- {"dt": "2024-12-02T13:03:33.271210", "lvl": "target", "msg": "x lava-38059/1/"}
- {"dt": "2024-12-02T13:03:33.271447", "lvl": "target", "msg": "x lava-38059/1/lava-test-runner.conf"}
- {"dt": "2024-12-02T13:03:33.271695", "lvl": "target", "msg": "x lava-38059/1/tests/"}
- {"dt": "2024-12-02T13:03:33.271940", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/"}
- {"dt": "2024-12-02T13:03:33.272466", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/inline/"}
- {"dt": "2024-12-02T13:03:33.312006", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/inline/bootrr.yaml"}
- {"dt": "2024-12-02T13:03:33.312555", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/run.sh"}
- {"dt": "2024-12-02T13:03:33.312895", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/testdef.yaml"}
- {"dt": "2024-12-02T13:03:33.313170", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/testdef_metadata"}
- {"dt": "2024-12-02T13:03:33.313425", "lvl": "target", "msg": "x lava-38059/1/tests/1_bootrr/uuid"}
- {"dt": "2024-12-02T13:03:33.313671", "lvl": "target", "msg": "x lava-38059/bin/"}
- {"dt": "2024-12-02T13:03:33.313914", "lvl": "target", "msg": "x lava-38059/bin/lava-add-keys"}
- {"dt": "2024-12-02T13:03:33.314155", "lvl": "target", "msg": "x lava-38059/bin/lava-add-sources"}
- {"dt": "2024-12-02T13:03:33.314394", "lvl": "target", "msg": "x lava-38059/bin/lava-background-process-start"}
- {"dt": "2024-12-02T13:03:33.314630", "lvl": "target", "msg": "x lava-38059/bin/lava-background-process-stop"}
- {"dt": "2024-12-02T13:03:33.314875", "lvl": "target", "msg": "x lava-38059/bin/lava-common-functions"}
- {"dt": "2024-12-02T13:03:33.315123", "lvl": "target", "msg": "x lava-38059/bin/lava-echo-ipv4"}
- {"dt": "2024-12-02T13:03:33.315654", "lvl": "target", "msg": "x lava-38059/bin/lava-install-packages"}
- {"dt": "2024-12-02T13:03:33.355275", "lvl": "target", "msg": "x lava-38059/bin/lava-installed-packages"}
- {"dt": "2024-12-02T13:03:33.355818", "lvl": "target", "msg": "x lava-38059/bin/lava-os-build"}
- {"dt": "2024-12-02T13:03:33.356124", "lvl": "target", "msg": "x lava-38059/bin/lava-probe-channel"}
- {"dt": "2024-12-02T13:03:33.356393", "lvl": "target", "msg": "x lava-38059/bin/lava-probe-ip"}
- {"dt": "2024-12-02T13:03:33.356649", "lvl": "target", "msg": "x lava-38059/bin/lava-target-ip"}
- {"dt": "2024-12-02T13:03:33.356937", "lvl": "target", "msg": "x lava-38059/bin/lava-target-mac"}
- {"dt": "2024-12-02T13:03:33.357183", "lvl": "target", "msg": "x lava-38059/bin/lava-target-storage"}
- {"dt": "2024-12-02T13:03:33.357423", "lvl": "target", "msg": "x lava-38059/bin/lava-test-case"}
- {"dt": "2024-12-02T13:03:33.357662", "lvl": "target", "msg": "x lava-38059/bin/lava-test-event"}
- {"dt": "2024-12-02T13:03:33.357900", "lvl": "target", "msg": "x lava-38059/bin/lava-test-feedback"}
- {"dt": "2024-12-02T13:03:33.358142", "lvl": "target", "msg": "x lava-38059/bin/lava-test-raise"}
- {"dt": "2024-12-02T13:03:33.358386", "lvl": "target", "msg": "x lava-38059/bin/lava-test-reference"}
- {"dt": "2024-12-02T13:03:33.358623", "lvl": "target", "msg": "x lava-38059/bin/lava-test-runner"}
- {"dt": "2024-12-02T13:03:33.359150", "lvl": "target", "msg": "x lava-38059/bin/lava-test-set"}
- {"dt": "2024-12-02T13:03:33.381067", "lvl": "target", "msg": "x lava-38059/bin/lava-test-shell"}
- {"dt": "2024-12-02T13:03:33.381612", "lvl": "target", "msg": "x lava-38059/environment"}
- {"dt": "2024-12-02T13:03:33.381918", "lvl": "target", "msg": "x lava-38059/lava"}
- {"dt": "2024-12-02T13:03:33.382185", "lvl": "target", "msg": "x lava-38059/lava.pub"}
- {"dt": "2024-12-02T13:03:33.382438", "lvl": "target", "msg": "x lava-38059/results/"}
- {"dt": "2024-12-02T13:03:33.384090", "lvl": "target", "msg": "x lava-38059/tests/"}
- {"dt": "2024-12-02T13:03:33.384904", "lvl": "debug", "msg": "end: 2.5 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:33.385365", "lvl": "debug", "msg": "start: 2.6 export-device-env (timeout 00:07:20) [common]"}
- {"dt": "2024-12-02T13:03:33.385787", "lvl": "debug", "msg": "end: 2.6 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:03:33.386166", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:01:55) [common]"}
- {"dt": "2024-12-02T13:03:33.386552", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T13:03:33.386929", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T13:03:33.387239", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-02T13:03:33.387551", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-02T13:03:33.488368", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-02T13:03:33.489134", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-02T13:03:33.495892", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-02T13:03:33.512272", "lvl": "debug", "msg": "Using /lava-38059"}
- {"dt": "2024-12-02T13:03:33.512828", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-02T13:03:33.613763", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh"}
- {"dt": "2024-12-02T13:03:33.623762", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-02T13:03:33.640021", "lvl": "input", "msg": ". /lava-38059/environment\n"}
- {"dt": "2024-12-02T13:03:33.741171", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38059/environment"}
- {"dt": "2024-12-02T13:03:33.751575", "lvl": "target", "msg": ". /lava-38059/environment"}
- {"dt": "2024-12-02T13:03:33.767864", "lvl": "input", "msg": "/lava-38059/bin/lava-test-runner /lava-38059/0\n"}
- {"dt": "2024-12-02T13:03:33.869000", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38059/bin/lava-test-runner /lava-38059/0"}
- {"dt": "2024-12-02T13:03:33.869725", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-02T13:03:33.879241", "lvl": "target", "msg": "/lava-38059/bin/lava-test-runner /lava-38059/0"}
- {"dt": "2024-12-02T13:03:33.944684", "lvl": "target", "msg": "+ export TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-02T13:03:33.945243", "lvl": "target", "msg": "+ TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-02T13:03:33.945552", "lvl": "target", "msg": "+ cd /lava-38059/0/tests/0_dmesg"}
- {"dt": "2024-12-02T13:03:33.945824", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-02T13:03:33.946081", "lvl": "target", "msg": "+ UUID=38059_1.5.4.1"}
- {"dt": "2024-12-02T13:03:33.946327", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T13:03:33.946568", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_dmesg 38059_1.5.4.1>"}
- {"dt": "2024-12-02T13:03:33.946859", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-02T13:03:33.947104", "lvl": "target", "msg": "+ dmesg --level=warn --notime -x -k"}
- {"dt": "2024-12-02T13:03:33.947347", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-02T13:03:33.947591", "lvl": "target", "msg": "+ dmesg --level=err --notime -x -k"}
- {"dt": "2024-12-02T13:03:33.947839", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T13:03:33.948080", "lvl": "target", "msg": "+ dmesg --level=crit --notime -x -k"}
- {"dt": "2024-12-02T13:03:33.948319", "lvl": "target", "msg": "+ test -s dmesg.crit"}
- {"dt": "2024-12-02T13:03:33.948556", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T13:03:33.948816", "lvl": "target", "msg": "++ cat dmesg.crit"}
- {"dt": "2024-12-02T13:03:33.949056", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T13:03:33.949298", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T13:03:33.949546", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_dmesg 38059_1.5.4.1"}
- {"dt": "2024-12-02T13:03:33.949614", "lvl": "info", "msg": "Starting test lava.0_dmesg (38059_1.5.4.1)"}
- {"dt": "2024-12-02T13:03:33.949700", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-02T13:03:33.987988", "lvl": "target", "msg": "+ lava-test-case crit --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T13:03:33.988529", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T13:03:33.988875", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T13:03:33.989154", "lvl": "target", "msg": "+ dmesg --level=alert --notime -x -k"}
- {"dt": "2024-12-02T13:03:33.989411", "lvl": "target", "msg": "+ test -s dmesg.alert"}
- {"dt": "2024-12-02T13:03:33.989658", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T13:03:33.989902", "lvl": "target", "msg": "++ cat dmesg.alert"}
- {"dt": "2024-12-02T13:03:33.990143", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T13:03:33.990380", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T13:03:33.990622", "lvl": "target", "msg": "+ lava-test-case alert --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T13:03:33.990871", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T13:03:33.991124", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T13:03:33.991632", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T13:03:33.992138", "lvl": "results", "msg": {"case": "crit", "definition": "0_dmesg", "endtc": ! "1340", "measurement": ! "0.0", "result": "pass", "starttc": ! "1340", "units": "lines"}}
- {"dt": "2024-12-02T13:03:33.992822", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T13:03:33.993195", "lvl": "results", "msg": {"case": "alert", "definition": "0_dmesg", "endtc": ! "1342", "measurement": ! "0.0", "result": "pass", "starttc": ! "1342", "units": "lines"}}
- {"dt": "2024-12-02T13:03:33.993808", "lvl": "target", "msg": "+ dmesg --level=emerg --notime -x -k"}
- {"dt": "2024-12-02T13:03:33.994078", "lvl": "target", "msg": "+ test -s dmesg.emerg"}
- {"dt": "2024-12-02T13:03:33.994327", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T13:03:34.035725", "lvl": "target", "msg": "++ cat dmesg.emerg"}
- {"dt": "2024-12-02T13:03:34.036261", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T13:03:34.036573", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T13:03:34.036887", "lvl": "target", "msg": "+ lava-test-case emerg --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T13:03:34.037153", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T13:03:34.037403", "lvl": "target", "msg": "+ cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"}
- {"dt": "2024-12-02T13:03:34.037652", "lvl": "target", "msg": "kern  :warn  : F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-02T13:03:34.037894", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T13:03:34.038135", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_dmesg 38059_1.5.4.1>"}
- {"dt": "2024-12-02T13:03:34.038643", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T13:03:34.039024", "lvl": "results", "msg": {"case": "emerg", "definition": "0_dmesg", "endtc": ! "1356", "measurement": ! "0.0", "result": "pass", "starttc": ! "1356", "units": "lines"}}
- {"dt": "2024-12-02T13:03:34.039634", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_dmesg 38059_1.5.4.1"}
- {"dt": "2024-12-02T13:03:34.039957", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-02T13:03:34.040206", "lvl": "info", "msg": "Ending test lava.0_dmesg (38059_1.5.4.1), duration 0.09"}
- {"dt": "2024-12-02T13:03:34.040609", "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": "38059_1.5.4.1"}}
- {"dt": "2024-12-02T13:03:34.041649", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-12-02T13:03:34.042170", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-02T13:03:34.042597", "lvl": "debug", "msg": "alert: pass\ncrit: pass\nemerg: pass\n"}
- {"dt": "2024-12-02T13:03:34.042973", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:34.043338", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:34.043707", "lvl": "info", "msg": "start: 4 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T13:03:34.044074", "lvl": "debug", "msg": "start: 4.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T13:03:34.044348", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-02T13:03:34.044622", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-02T13:03:34.145528", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-02T13:03:34.146226", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-02T13:03:34.146727", "lvl": "debug", "msg": "Using /lava-38059"}
- {"dt": "2024-12-02T13:03:34.147037", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-02T13:03:34.247923", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-02T13:03:34.248728", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-02T13:03:34.249398", "lvl": "input", "msg": ". /lava-38059/environment\n"}
- {"dt": "2024-12-02T13:03:34.350239", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh. /lava-38059/environment"}
- {"dt": "2024-12-02T13:03:34.350990", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:34.351630", "lvl": "input", "msg": "/lava-38059/bin/lava-test-runner /lava-38059/1\n"}
- {"dt": "2024-12-02T13:03:34.452536", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38059/environment/lava-38059/bin/lava-test-runner /lava-38059/1"}
- {"dt": "2024-12-02T13:03:34.453241", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-02T13:03:34.453739", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T13:03:34.469904", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38059/bin/lava-test-runner /lava-38059/1"}
- {"dt": "2024-12-02T13:03:34.515500", "lvl": "target", "msg": "+ export TESTRUN_ID=1_bootrr"}
- {"dt": "2024-12-02T13:03:34.516024", "lvl": "target", "msg": "+ TESTRUN_[   39.201312] <LAVA_SIGNAL_STARTRUN 1_bootrr 38059_1.5.4.5>"}
- {"dt": "2024-12-02T13:03:34.516329", "lvl": "target", "msg": "ID=1_bootrr"}
- {"dt": "2024-12-02T13:03:34.516592", "lvl": "target", "msg": "+ cd /lava-38059/1/te[   39.209744] <LAVA_SIGNAL_ENDRUN 1_bootrr 38059_1.5.4.5>"}
- {"dt": "2024-12-02T13:03:34.516891", "lvl": "target", "msg": "sts/1_bootrr"}
- {"dt": "2024-12-02T13:03:34.517131", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-02T13:03:34.517368", "lvl": "target", "msg": "+ UUID=38059_1.5.4.5"}
- {"dt": "2024-12-02T13:03:34.517606", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T13:03:34.517838", "lvl": "target", "msg": "+ export PATH=/opt/bootrr/helpers:/lava-38059/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-02T13:03:34.518345", "lvl": "debug", "msg": "Received signal: <STARTRUN> 1_bootrr 38059_1.5.4.5"}
- {"dt": "2024-12-02T13:03:34.518633", "lvl": "info", "msg": "Starting test lava.1_bootrr (38059_1.5.4.5)"}
- {"dt": "2024-12-02T13:03:34.518958", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-02T13:03:34.519342", "lvl": "debug", "msg": "Received signal: <ENDRUN> 1_bootrr 38059_1.5.4.5"}
- {"dt": "2024-12-02T13:03:34.519638", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-02T13:03:34.519886", "lvl": "info", "msg": "Ending test lava.1_bootrr (38059_1.5.4.5), duration 0.00"}
- {"dt": "2024-12-02T13:03:34.520020", "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": "38059_1.5.4.5"}}
- {"dt": "2024-12-02T13:03:34.520243", "lvl": "target", "msg": "+ PATH=/opt/bootrr/helpers:/lava-38059/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-02T13:03:34.541657", "lvl": "target", "msg": "+ cd /opt/bootrr"}
- {"dt": "2024-12-02T13:03:34.542183", "lvl": "target", "msg": "/lava-38059/1/tests/1_bootrr/run.sh: line 15: cd: /opt/bootrr: No such file or directory"}
- {"dt": "2024-12-02T13:03:34.542483", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T13:03:34.543001", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-02T13:03:34.543432", "lvl": "debug", "msg": "end: 4.1 lava-test-shell (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T13:03:34.543795", "lvl": "info", "msg": "end: 4 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:34.544170", "lvl": "info", "msg": "start: 5 finalize (timeout 00:07:19) [common]"}
- {"dt": "2024-12-02T13:03:34.544574", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-12-02T13:03:34.545153", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'off' '2'"}
- {"dt": "2024-12-02T13:03:35.591067", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T13:03:35.591733", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-02T13:03:35.592991", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-02T13:03:35.693840", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:35.694420", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "1.15", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T13:03:35.695147", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:07:18) [common]"}
- {"dt": "2024-12-02T13:03:35.696211", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-12-02T13:03:35.696828", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-02T13:03:36.697027", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-02T13:03:36.697660", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-02T13:03:36.698036", "lvl": "target", "msg": "root@raspberrypi5:~# \0"}
- {"dt": "2024-12-02T13:03:36.798913", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T13:03:36.799461", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-02T13:03:36.799790", "lvl": "debug", "msg": "clean NBD port 61986"}
- {"dt": "2024-12-02T13:03:36.912113", "lvl": "info", "msg": "end: 5 finalize (duration 00:00:02) [common]"}
- {"dt": "2024-12-02T13:03:36.913366", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-02T13:03:36.913878", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/initrd"}
- {"dt": "2024-12-02T13:03:36.917326", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/kernel"}
- {"dt": "2024-12-02T13:03:36.919975", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/dtb"}
- {"dt": "2024-12-02T13:03:36.920198", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38059/nbd-deploy-st1dn29v/nbdroot"}
- {"dt": "2024-12-02T13:03:37.317014", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38059"}
- {"dt": "2024-12-02T13:03:37.321959", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38059"}
- {"dt": "2024-12-02T13:03:37.322118", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-02T13:03:37.322201", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
