- {"dt": "2024-01-25T11:19:12.081165", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.08"}
- {"dt": "2024-01-25T11:19:12.081270", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-01-25T11:19:12.081344", "lvl": "info", "msg": "Start time: 2024-01-25 11:19:12.081340+00:00 (UTC)"}
- {"dt": "2024-01-25T11:19:12.082358", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2bHjk40p4iBVk4wj4jcVWOIXw5P/Image.gz exists"}
- {"dt": "2024-01-25T11:19:12.517247", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-01-25T11:19:13.384155", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tmp/xfstests-artifacts/root_fs.arm64.ext4.xz exists"}
- {"dt": "2024-01-25T11:19:13.384424", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2bHjk40p4iBVk4wj4jcVWOIXw5P/modules.tar.xz exists"}
- {"dt": "2024-01-25T11:19:13.503459", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-01-25T11:19:14.282481", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tmp/xfstests-artifacts/xfstests-arm64.tar.xz exists"}
- {"dt": "2024-01-25T11:19:14.293699", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.1.2+ds-1, host architecture: amd64"}
- {"dt": "2024-01-25T11:19:14.293993", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-01-25T11:19:14.294120", "lvl": "warning", "msg": "Missing image arg for rootfs.overlay-00"}
- {"dt": "2024-01-25T11:19:14.294342", "lvl": "info", "msg": "validate duration: 2.21"}
- {"dt": "2024-01-25T11:19:14.294445", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-01-25T11:19:14.294643", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.294786", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.295099", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr"}
- {"dt": "2024-01-25T11:19:14.295379", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin"}
- {"dt": "2024-01-25T11:19:14.295589", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/tests"}
- {"dt": "2024-01-25T11:19:14.295786", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/results"}
- {"dt": "2024-01-25T11:19:14.295945", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-add-keys"}
- {"dt": "2024-01-25T11:19:14.296224", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-add-sources"}
- {"dt": "2024-01-25T11:19:14.296501", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-01-25T11:19:14.296768", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-01-25T11:19:14.297042", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-common-functions"}
- {"dt": "2024-01-25T11:19:14.297298", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-01-25T11:19:14.297551", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-install-packages"}
- {"dt": "2024-01-25T11:19:14.297799", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-01-25T11:19:14.298054", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-os-build"}
- {"dt": "2024-01-25T11:19:14.298309", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-01-25T11:19:14.298563", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-01-25T11:19:14.298817", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-target-ip"}
- {"dt": "2024-01-25T11:19:14.299084", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-target-mac"}
- {"dt": "2024-01-25T11:19:14.299342", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-target-storage"}
- {"dt": "2024-01-25T11:19:14.299606", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-case"}
- {"dt": "2024-01-25T11:19:14.299867", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-event"}
- {"dt": "2024-01-25T11:19:14.300126", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-01-25T11:19:14.300394", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-raise"}
- {"dt": "2024-01-25T11:19:14.300650", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-reference"}
- {"dt": "2024-01-25T11:19:14.300903", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-runner"}
- {"dt": "2024-01-25T11:19:14.301157", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-set"}
- {"dt": "2024-01-25T11:19:14.301413", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-test-shell"}
- {"dt": "2024-01-25T11:19:14.301673", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-01-25T11:19:14.301966", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-01-25T11:19:14.302197", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/environment"}
- {"dt": "2024-01-25T11:19:14.302381", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-01-25T11:19:14.302488", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-01-25T11:19:14.302583", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=10.0.2.100"}
- {"dt": "2024-01-25T11:19:14.302745", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.302848", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-01-25T11:19:14.302977", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.303101", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.303188", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-01-25T11:19:14.303291", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.303403", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.303505", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-01-25T11:19:14.303626", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.303721", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-01-25T11:19:14.304274", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-01-25T11:19:14.304409", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.304530", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.305322", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.305436", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "commands", "path": "command", "uuid": "1_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:14.305662", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.306642", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.306761", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "commands", "path": "command", "skipped test-install-overlay": "1_1.1.3.1", "uuid": "1_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:14.307004", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.310641", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/0/tests/0_commands test_uuid 1_1.1.3.1"}
- {"dt": "2024-01-25T11:19:14.311069", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.311187", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/0/tests/0_commands/run.sh", "from": "inline", "name": "commands", "path": "command", "uuid": "1_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:14.311387", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-01-25T11:19:14.311474", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/lava-overlay-7a0mhtgr/lava-1/0 for stage 0"}
- {"dt": "2024-01-25T11:19:14.311630", "lvl": "debug", "msg": "- 0_commands"}
- {"dt": "2024-01-25T11:19:14.311795", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.311924", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323343", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323509", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323641", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323766", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323885", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.323980", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-01-25T11:19:14.324090", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.324204", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.324314", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:14.324429", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.324545", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:19:14.324775", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2bHjk40p4iBVk4wj4jcVWOIXw5P/Image.gz"}
- {"dt": "2024-01-25T11:19:14.324892", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/kernel/Image"}
- {"dt": "2024-01-25T11:19:14.324986", "lvl": "debug", "msg": "total size: 17645645 (16\u202FMB)"}
- {"dt": "2024-01-25T11:19:14.325082", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-01-25T11:19:15.179674", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:15.632215", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:15.766310", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-01-25T11:19:15.851536", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-01-25T11:19:15.927106", "lvl": "debug", "msg": "progress  20\u202F% (3\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.135865", "lvl": "debug", "msg": "progress  25\u202F% (4\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.160317", "lvl": "debug", "msg": "progress  30\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.190139", "lvl": "debug", "msg": "progress  35\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.254343", "lvl": "debug", "msg": "progress  40\u202F% (6\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.329960", "lvl": "debug", "msg": "progress  45\u202F% (7\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.405600", "lvl": "debug", "msg": "progress  50\u202F% (8\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.480994", "lvl": "debug", "msg": "progress  55\u202F% (9\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.556594", "lvl": "debug", "msg": "progress  60\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.632144", "lvl": "debug", "msg": "progress  65\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.705091", "lvl": "debug", "msg": "progress  70\u202F% (11\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.780531", "lvl": "debug", "msg": "progress  75\u202F% (12\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.856142", "lvl": "debug", "msg": "progress  80\u202F% (13\u202FMB)"}
- {"dt": "2024-01-25T11:19:16.931720", "lvl": "debug", "msg": "progress  85\u202F% (14\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.007152", "lvl": "debug", "msg": "progress  90\u202F% (15\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.082976", "lvl": "debug", "msg": "progress  95\u202F% (16\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.156705", "lvl": "debug", "msg": "progress 100\u202F% (16\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.188882", "lvl": "info", "msg": "16\u202FMB downloaded in 2.86\u202Fs (5.88\u202FMB/s)"}
- {"dt": "2024-01-25T11:19:17.189131", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-01-25T11:19:17.189263", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.86", "extra": {"label": "kernel", "md5sum": "78255b2778c37d2c2979caa4461b81c6", "sha256sum": "8f5253251689b5bd98183843d42b829c9f831d2cdbbf8009631b1aaa5c0d6e6d", "sha512sum": "1eed0fd1d27af93260d0b3c5a0b02c7718665f022ef547e264b1062ca3b8bacda34cfb18ab5a822a822a1ce3639bffb217d51f96ca620e8fb01fd765f6336eb6", "size": ! "17645645"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:17.189513", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-01-25T11:19:17.189651", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:57) [common]"}
- {"dt": "2024-01-25T11:19:17.189782", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:57) [common]"}
- {"dt": "2024-01-25T11:19:17.190044", "lvl": "info", "msg": "downloading file:///home/anders/src/tmp/xfstests-artifacts/root_fs.arm64.ext4.xz"}
- {"dt": "2024-01-25T11:19:17.190158", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs/root_fs.arm64.ext4"}
- {"dt": "2024-01-25T11:19:17.190251", "lvl": "debug", "msg": "total size: 109690612 (104\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.190339", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-01-25T11:19:17.191156", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.476781", "lvl": "debug", "msg": "progress   5\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:17.751979", "lvl": "debug", "msg": "progress  10\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:18.008130", "lvl": "debug", "msg": "progress  15\u202F% (15\u202FMB)"}
- {"dt": "2024-01-25T11:19:18.235894", "lvl": "debug", "msg": "progress  20\u202F% (20\u202FMB)"}
- {"dt": "2024-01-25T11:19:18.465533", "lvl": "debug", "msg": "progress  25\u202F% (26\u202FMB)"}
- {"dt": "2024-01-25T11:19:18.702706", "lvl": "debug", "msg": "progress  30\u202F% (31\u202FMB)"}
- {"dt": "2024-01-25T11:19:18.928806", "lvl": "debug", "msg": "progress  35\u202F% (36\u202FMB)"}
- {"dt": "2024-01-25T11:19:19.161260", "lvl": "debug", "msg": "progress  40\u202F% (41\u202FMB)"}
- {"dt": "2024-01-25T11:19:19.406681", "lvl": "debug", "msg": "progress  45\u202F% (47\u202FMB)"}
- {"dt": "2024-01-25T11:19:19.639044", "lvl": "debug", "msg": "progress  50\u202F% (52\u202FMB)"}
- {"dt": "2024-01-25T11:19:19.892197", "lvl": "debug", "msg": "progress  55\u202F% (57\u202FMB)"}
- {"dt": "2024-01-25T11:19:20.126085", "lvl": "debug", "msg": "progress  60\u202F% (62\u202FMB)"}
- {"dt": "2024-01-25T11:19:20.358866", "lvl": "debug", "msg": "progress  65\u202F% (68\u202FMB)"}
- {"dt": "2024-01-25T11:19:20.605766", "lvl": "debug", "msg": "progress  70\u202F% (73\u202FMB)"}
- {"dt": "2024-01-25T11:19:20.851482", "lvl": "debug", "msg": "progress  75\u202F% (78\u202FMB)"}
- {"dt": "2024-01-25T11:19:21.094967", "lvl": "debug", "msg": "progress  80\u202F% (83\u202FMB)"}
- {"dt": "2024-01-25T11:19:21.353452", "lvl": "debug", "msg": "progress  85\u202F% (88\u202FMB)"}
- {"dt": "2024-01-25T11:19:21.609031", "lvl": "debug", "msg": "progress  90\u202F% (94\u202FMB)"}
- {"dt": "2024-01-25T11:19:21.839710", "lvl": "debug", "msg": "progress  95\u202F% (99\u202FMB)"}
- {"dt": "2024-01-25T11:19:24.049579", "lvl": "debug", "msg": "progress 100\u202F% (104\u202FMB)"}
- {"dt": "2024-01-25T11:19:24.994144", "lvl": "info", "msg": "104\u202FMB downloaded in 7.80\u202Fs (13.40\u202FMB/s)"}
- {"dt": "2024-01-25T11:19:24.994369", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:08) [common]"}
- {"dt": "2024-01-25T11:19:24.994440", "lvl": "results", "msg": {"case": "file-download", "definition": "lava", "duration": "7.80", "extra": {"label": "rootfs", "md5sum": "32a4226c10d530e6ebc0800e8665c824", "sha256sum": "7fd9a7fa21675d9c6bdb8a4005505374acc4121ec66c82312c8873216e9c02bd", "sha512sum": "4a81be7aafe71573b50e84d182d639cc93086289545ff8e20a5f2c7c3dbdfe7f6859f2f5f2b76fc8dd542cea9892a1ed2d8a5e995c9135042018ee514920b97c", "size": ! "109690612"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:24.994577", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:49) [common]"}
- {"dt": "2024-01-25T11:19:24.994645", "lvl": "debug", "msg": "start: 1.5.2.1 http-download (timeout 00:04:49) [common]"}
- {"dt": "2024-01-25T11:19:24.994830", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2bHjk40p4iBVk4wj4jcVWOIXw5P/modules.tar.xz"}
- {"dt": "2024-01-25T11:19:24.994887", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.modules/modules.tar"}
- {"dt": "2024-01-25T11:19:24.994949", "lvl": "debug", "msg": "total size: 11311756 (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:24.994996", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-01-25T11:19:25.905406", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.240122", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.364614", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.461349", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.512607", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.560764", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.608879", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.842601", "lvl": "debug", "msg": "progress  35\u202F% (3\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.867336", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.890617", "lvl": "debug", "msg": "progress  45\u202F% (4\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.916202", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.939963", "lvl": "debug", "msg": "progress  55\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:26.966821", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.014670", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.062683", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.219097", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.258456", "lvl": "debug", "msg": "progress  80\u202F% (8\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.283093", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.309090", "lvl": "debug", "msg": "progress  90\u202F% (9\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.338098", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.382457", "lvl": "debug", "msg": "progress 100\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.384742", "lvl": "info", "msg": "10\u202FMB downloaded in 2.39\u202Fs (4.51\u202FMB/s)"}
- {"dt": "2024-01-25T11:19:27.384993", "lvl": "debug", "msg": "end: 1.5.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-01-25T11:19:27.385114", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.39", "extra": {"label": "rootfs.modules", "md5sum": "46e2c4ed5939a2db40e1b1f4d39c9995", "sha256sum": "21cbf3ee881d4c34d63eae76345f27db2698f40d9b437cc6b00da152c4717177", "sha512sum": "d29b6778b9748eb504877ca5242646ae2c1827b01cc8b362788745a5d3c75690b4a8c9edcd178e727ff3ca10dd1fa4c7de759dbea3248f181c7e6194af139ca5", "size": ! "11311756"}, "level": "1.5.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:27.385372", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-01-25T11:19:27.385529", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:04:47) [common]"}
- {"dt": "2024-01-25T11:19:27.385662", "lvl": "debug", "msg": "start: 1.5.3.1 file-download (timeout 00:04:47) [common]"}
- {"dt": "2024-01-25T11:19:27.385937", "lvl": "info", "msg": "downloading file:///home/anders/src/tmp/xfstests-artifacts/xfstests-arm64.tar.xz"}
- {"dt": "2024-01-25T11:19:27.386064", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.overlay-00/xfstests-arm64.tar"}
- {"dt": "2024-01-25T11:19:27.386165", "lvl": "debug", "msg": "total size: 11890128 (11\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.386264", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-01-25T11:19:27.387024", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.411212", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.437503", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.462744", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.487543", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.512671", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.536860", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.562840", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.589467", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.614542", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.641378", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.666720", "lvl": "debug", "msg": "progress  55\u202F% (6\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.692098", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.721462", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.750519", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.776882", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.810602", "lvl": "debug", "msg": "progress  80\u202F% (9\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.857656", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.883625", "lvl": "debug", "msg": "progress  90\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.913211", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.941399", "lvl": "debug", "msg": "progress 100\u202F% (11\u202FMB)"}
- {"dt": "2024-01-25T11:19:27.947081", "lvl": "info", "msg": "11\u202FMB downloaded in 0.56\u202Fs (20.22\u202FMB/s)"}
- {"dt": "2024-01-25T11:19:27.947260", "lvl": "debug", "msg": "end: 1.5.3.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2024-01-25T11:19:27.947382", "lvl": "results", "msg": {"case": "file-download", "definition": "lava", "duration": "0.56", "extra": {"label": "rootfs.overlay-00", "md5sum": "bac99584e6e37c90e62491fc5594b1aa", "sha256sum": "1186027f329e2d46b1331cdfd9c90b3bf08552a34cdee5599dd092a850d731d8", "sha512sum": "dafdbde97f01d0038fad88b7efd52fd39ec276e0fc06114eac0ac32f93aa1750d308ff50b9671940dae0ffbe0cf30f24b8e80977ab8fbe9ca653aeb54bf8c1c4", "size": ! "11890128"}, "level": "1.5.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:27.947639", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-01-25T11:19:27.947739", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:04:46) [common]"}
- {"dt": "2024-01-25T11:19:27.947825", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs/root_fs.arm64.ext4'"}
- {"dt": "2024-01-25T11:19:30.267277", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-01-25T11:19:30.268419", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/compress-overlay-z9yck98m/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-01-25T11:19:30.273111", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2024-01-25T11:19:30.484854", "lvl": "debug", "msg": "- rootfs.overlay-00: '/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.overlay-00/xfstests-arm64.tar' to '/tmp/'"}
- {"dt": "2024-01-25T11:19:31.207180", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2024-01-25T11:19:31.207720", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:14) [common]"}
- {"dt": "2024-01-25T11:19:31.207885", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:17) [common]"}
- {"dt": "2024-01-25T11:19:31.208045", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:30:00) [common]"}
- {"dt": "2024-01-25T11:19:31.208200", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:30:00) [common]"}
- {"dt": "2024-01-25T11:19:31.208336", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:30:00) [common]"}
- {"dt": "2024-01-25T11:19:31.208833", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu max,pauth-impdef=on -machine virt,virtualization=on,gic-version=3,mte=on -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs/root_fs.arm64.ext4,if=none,format=raw,id=hd0 -device virtio-blk-device,drive=hd0"}
- {"dt": "2024-01-25T11:19:31.214387", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-01-25T11:19:31.214967", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:31.215110", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.01", "extra": {"host_arch": "amd64", "job_arch": "arm64", "qemu_version": "1:8.1.2+ds-1"}, "level": "2.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-01-25T11:19:31.215370", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:19:31.215516", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:30:00) [common]"}
- {"dt": "2024-01-25T11:19:31.215654", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-01-25T11:19:31.215774", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2024-01-25T11:19:31.474061", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-01-25T11:19:31.474379", "lvl": "target", "msg": "[    0.000000] Linux version 6.8.0-rc1 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 13.2.0-7) 13.2.0, GNU ld (GNU Binutils for Debian) 2.41) #1 SMP PREEMPT @1705879347"}
- {"dt": "2024-01-25T11:19:31.474570", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-01-25T11:19:31.474709", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-01-25T11:19:31.474877", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-01-25T11:19:31.475041", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-01-25T11:19:31.475180", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-01-25T11:19:31.475289", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-01-25T11:19:31.475667", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:30:00) [common]"}
- {"dt": "2024-01-25T11:19:31.475801", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-01-25T11:19:31.475936", "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-01-25T11:19:31.476069", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-01-25T11:19:31.476171", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-01-25T11:19:31.476272", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-01-25T11:19:31.476362", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2024-01-25T11:19:31.476548", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:30:00)"}
- {"dt": "2024-01-25T11:19:31.477933", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-01-25T11:19:31.478093", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-01-25T11:19:31.478513", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f5f19c0-0x13f5f3fff]"}
- {"dt": "2024-01-25T11:19:31.480383", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-01-25T11:19:31.480754", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-01-25T11:19:31.480876", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-01-25T11:19:31.481040", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-01-25T11:19:31.481189", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-01-25T11:19:31.481335", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-01-25T11:19:31.481497", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-01-25T11:19:31.481874", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-01-25T11:19:31.504498", "lvl": "target", "msg": "[    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1"}
- {"dt": "2024-01-25T11:19:31.505236", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-01-25T11:19:31.505391", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-01-25T11:19:31.505531", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-01-25T11:19:31.505756", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-01-25T11:19:31.505911", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-01-25T11:19:31.507881", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 32 pages/cpu s90216 r8192 d32664 u131072"}
- {"dt": "2024-01-25T11:19:31.508496", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s90216 r8192 d32664 u131072 alloc=32*4096"}
- {"dt": "2024-01-25T11:19:31.508899", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-01-25T11:19:31.511069", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-01-25T11:19:31.512568", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-01-25T11:19:31.512789", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-01-25T11:19:31.512945", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-01-25T11:19:31.513108", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-01-25T11:19:31.513312", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-01-25T11:19:31.513467", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-01-25T11:19:31.513796", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-01-25T11:19:31.516635", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-01-25T11:19:31.519763", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2024-01-25T11:19:31.521784", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \\\"verbose\\\", will be passed to user space."}
- {"dt": "2024-01-25T11:19:31.525693", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-01-25T11:19:31.526801", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-01-25T11:19:31.528105", "lvl": "target", "msg": "<6>[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-01-25T11:19:31.528258", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1032192"}
- {"dt": "2024-01-25T11:19:31.528359", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2024-01-25T11:19:31.528870", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-01-25T11:19:31.529084", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-01-25T11:19:31.580304", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)"}
- {"dt": "2024-01-25T11:19:31.595559", "lvl": "target", "msg": "<6>[    0.000000] Memory: 3954468K/4194304K available (22016K kernel code, 6302K rwdata, 13608K rodata, 14400K init, 703K bss, 207068K reserved, 32768K cma-reserved)"}
- {"dt": "2024-01-25T11:19:31.600213", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-01-25T11:19:31.601085", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocating 72056 entries in 282 pages"}
- {"dt": "2024-01-25T11:19:32.069197", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocated 282 pages with 4 groups"}
- {"dt": "2024-01-25T11:19:32.071562", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-01-25T11:19:32.077144", "lvl": "target", "msg": "<6>[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-01-25T11:19:32.077279", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-01-25T11:19:32.077401", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2024-01-25T11:19:32.077567", "lvl": "target", "msg": "<6>[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-01-25T11:19:32.077708", "lvl": "target", "msg": "<6>[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-01-25T11:19:32.077847", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-01-25T11:19:32.078124", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-01-25T11:19:32.078320", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-01-25T11:19:32.088669", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-01-25T11:19:32.093243", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-01-25T11:19:32.093400", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-01-25T11:19:32.093515", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-01-25T11:19:32.094889", "lvl": "target", "msg": "<6>[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-01-25T11:19:32.095046", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-01-25T11:19:32.095564", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-01-25T11:19:32.099962", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-01-25T11:19:32.100742", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100210000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-01-25T11:19:32.101028", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100220000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-01-25T11:19:32.101693", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000100230000"}
- {"dt": "2024-01-25T11:19:32.102350", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100240000"}
- {"dt": "2024-01-25T11:19:32.104061", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-01-25T11:19:32.109834", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-01-25T11:19:32.110215", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-01-25T11:19:32.110732", "lvl": "target", "msg": "<6>[    0.000052] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-01-25T11:19:32.113343", "lvl": "target", "msg": "<6>[    0.002536] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)"}
- {"dt": "2024-01-25T11:19:32.126085", "lvl": "target", "msg": "<6>[    0.013672] Console: colour dummy device 80x25"}
- {"dt": "2024-01-25T11:19:32.129135", "lvl": "target", "msg": "<6>[    0.017993] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-01-25T11:19:32.129544", "lvl": "target", "msg": "<6>[    0.018943] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-01-25T11:19:32.131202", "lvl": "target", "msg": "<6>[    0.020372] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-01-25T11:19:32.134164", "lvl": "target", "msg": "<6>[    0.023412] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-01-25T11:19:32.134314", "lvl": "target", "msg": "<6>[    0.023693] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-01-25T11:19:32.158202", "lvl": "target", "msg": "<4>[    0.047427] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-01-25T11:19:32.170350", "lvl": "target", "msg": "<6>[    0.059587] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-01-25T11:19:32.171971", "lvl": "target", "msg": "<6>[    0.061226] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-01-25T11:19:32.172417", "lvl": "target", "msg": "<6>[    0.061762] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-01-25T11:19:32.174654", "lvl": "target", "msg": "<6>[    0.063898] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-01-25T11:19:32.174796", "lvl": "target", "msg": "<6>[    0.064130] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-01-25T11:19:32.182107", "lvl": "target", "msg": "<6>[    0.071373] Platform MSI: its@8080000 domain created"}
- {"dt": "2024-01-25T11:19:32.182812", "lvl": "target", "msg": "<6>[    0.071949] PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2024-01-25T11:19:32.183964", "lvl": "target", "msg": "<6>[    0.073246] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-01-25T11:19:32.185910", "lvl": "target", "msg": "<6>[    0.075186] EFI services will not be available."}
- {"dt": "2024-01-25T11:19:32.187820", "lvl": "target", "msg": "<6>[    0.077098] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-01-25T11:19:32.211665", "lvl": "target", "msg": "<6>[    0.085395] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-01-25T11:19:32.211832", "lvl": "target", "msg": "<6>[    0.089039] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-01-25T11:19:32.212290", "lvl": "target", "msg": "<6>[    0.090704] GICv3: CPU1: using allocated LPI pending table @0x0000000100250000"}
- {"dt": "2024-01-25T11:19:32.212432", "lvl": "target", "msg": "<6>[    0.094359] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-01-25T11:19:32.212558", "lvl": "target", "msg": "<6>[    0.100881] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-01-25T11:19:32.212691", "lvl": "target", "msg": "<6>[    0.102090] SMP: Total of 2 processors activated."}
- {"dt": "2024-01-25T11:19:32.212828", "lvl": "target", "msg": "<6>[    0.102269] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-01-25T11:19:32.213083", "lvl": "target", "msg": "<6>[    0.102477] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-01-25T11:19:32.213286", "lvl": "target", "msg": "<6>[    0.102710] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-01-25T11:19:32.213460", "lvl": "target", "msg": "<6>[    0.102890] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-01-25T11:19:32.213657", "lvl": "target", "msg": "<6>[    0.103057] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-01-25T11:19:32.213939", "lvl": "target", "msg": "<6>[    0.103269] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-01-25T11:19:32.214162", "lvl": "target", "msg": "<6>[    0.103562] CPU features: detected: Common not Private translations"}
- {"dt": "2024-01-25T11:19:32.214342", "lvl": "target", "msg": "<6>[    0.103757] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-01-25T11:19:32.214566", "lvl": "target", "msg": "<6>[    0.103934] CPU features: detected: Data cache clean to Point of Deep Persistence"}
- {"dt": "2024-01-25T11:19:32.214785", "lvl": "target", "msg": "<6>[    0.104161] CPU features: detected: Data cache clean to Point of Persistence"}
- {"dt": "2024-01-25T11:19:32.215012", "lvl": "target", "msg": "<6>[    0.104383] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-01-25T11:19:32.215146", "lvl": "target", "msg": "<6>[    0.104604] CPU features: detected: E0PD"}
- {"dt": "2024-01-25T11:19:32.215355", "lvl": "target", "msg": "<6>[    0.104742] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-01-25T11:19:32.215548", "lvl": "target", "msg": "<6>[    0.104937] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-01-25T11:19:32.215702", "lvl": "target", "msg": "<6>[    0.105131] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-01-25T11:19:32.216189", "lvl": "target", "msg": "<6>[    0.105334] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-01-25T11:19:32.216392", "lvl": "target", "msg": "<6>[    0.105804] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-01-25T11:19:32.216640", "lvl": "target", "msg": "<6>[    0.106023] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-01-25T11:19:32.216816", "lvl": "target", "msg": "<6>[    0.106237] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-01-25T11:19:32.217025", "lvl": "target", "msg": "<6>[    0.106447] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-01-25T11:19:32.217206", "lvl": "target", "msg": "<6>[    0.106623] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-01-25T11:19:32.217367", "lvl": "target", "msg": "<6>[    0.106792] CPU features: detected: Random Number Generator"}
- {"dt": "2024-01-25T11:19:32.217532", "lvl": "target", "msg": "<6>[    0.106963] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-01-25T11:19:32.217704", "lvl": "target", "msg": "<6>[    0.107130] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-01-25T11:19:32.217896", "lvl": "target", "msg": "<6>[    0.107302] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-01-25T11:19:32.218106", "lvl": "target", "msg": "<6>[    0.107518] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-01-25T11:19:32.218241", "lvl": "target", "msg": "<6>[    0.107700] CPU features: detected: FA64"}
- {"dt": "2024-01-25T11:19:32.218445", "lvl": "target", "msg": "<6>[    0.107834] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-01-25T11:19:32.218621", "lvl": "target", "msg": "<6>[    0.108041] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-01-25T11:19:32.237516", "lvl": "target", "msg": "<6>[    0.124889] alternatives: applying system-wide alternatives"}
- {"dt": "2024-01-25T11:19:32.248521", "lvl": "target", "msg": "<6>[    0.137376] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-01-25T11:19:32.248811", "lvl": "target", "msg": "<6>[    0.138188] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-01-25T11:19:32.248995", "lvl": "target", "msg": "<6>[    0.138412] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-01-25T11:19:32.250379", "lvl": "target", "msg": "<6>[    0.139663] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-01-25T11:19:32.250494", "lvl": "target", "msg": "<6>[    0.139876] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-01-25T11:19:32.250648", "lvl": "target", "msg": "<6>[    0.140069] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-01-25T11:19:32.289354", "lvl": "target", "msg": "<6>[    0.178578] devtmpfs: initialized"}
- {"dt": "2024-01-25T11:19:32.339483", "lvl": "target", "msg": "<6>[    0.228181] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-01-25T11:19:32.340747", "lvl": "target", "msg": "<6>[    0.229838] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-01-25T11:19:32.349619", "lvl": "target", "msg": "<6>[    0.238811] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-01-25T11:19:32.364259", "lvl": "target", "msg": "<6>[    0.253549] DMI not present or invalid."}
- {"dt": "2024-01-25T11:19:32.403005", "lvl": "target", "msg": "<6>[    0.291037] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-01-25T11:19:32.424788", "lvl": "target", "msg": "<6>[    0.313427] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-01-25T11:19:32.430131", "lvl": "target", "msg": "<6>[    0.319024] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-01-25T11:19:32.433465", "lvl": "target", "msg": "<6>[    0.322344] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-01-25T11:19:32.434624", "lvl": "target", "msg": "<6>[    0.323819] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-01-25T11:19:32.443317", "lvl": "target", "msg": "<5>[    0.332376] audit: type=2000 audit(0.300:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-01-25T11:19:32.452127", "lvl": "target", "msg": "<6>[    0.341274] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-01-25T11:19:32.452932", "lvl": "target", "msg": "<6>[    0.341382] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-01-25T11:19:32.453065", "lvl": "target", "msg": "<6>[    0.342208] cpuidle: using governor menu"}
- {"dt": "2024-01-25T11:19:32.455013", "lvl": "target", "msg": "<6>[    0.344212] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-01-25T11:19:32.456497", "lvl": "target", "msg": "<6>[    0.345760] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-01-25T11:19:32.468699", "lvl": "target", "msg": "<6>[    0.357960] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-01-25T11:19:32.600849", "lvl": "target", "msg": "<6>[    0.490082] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-01-25T11:19:32.602373", "lvl": "target", "msg": "<6>[    0.491547] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-01-25T11:19:32.602512", "lvl": "target", "msg": "<6>[    0.491547] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-01-25T11:19:32.602632", "lvl": "target", "msg": "<6>[    0.492050] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-01-25T11:19:32.602756", "lvl": "target", "msg": "<6>[    0.492050] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-01-25T11:19:32.649552", "lvl": "target", "msg": "<6>[    0.538105] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-01-25T11:19:32.650758", "lvl": "target", "msg": "<6>[    0.539630] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-01-25T11:19:32.661820", "lvl": "target", "msg": "<6>[    0.539910] Modules: 509984 pages in range for PLT usage"}
- {"dt": "2024-01-25T11:19:32.662317", "lvl": "target", "msg": "<6>[    0.550952] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-01-25T11:19:32.663024", "lvl": "target", "msg": "<6>[    0.551945] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-01-25T11:19:32.663625", "lvl": "target", "msg": "<6>[    0.552728] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-01-25T11:19:32.664302", "lvl": "target", "msg": "<6>[    0.553258] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-01-25T11:19:32.664799", "lvl": "target", "msg": "<6>[    0.553832] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-01-25T11:19:32.665290", "lvl": "target", "msg": "<6>[    0.554443] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-01-25T11:19:32.665840", "lvl": "target", "msg": "<6>[    0.554947] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-01-25T11:19:32.666328", "lvl": "target", "msg": "<6>[    0.555477] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-01-25T11:19:32.684032", "lvl": "target", "msg": "<6>[    0.573300] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-01-25T11:19:32.692016", "lvl": "target", "msg": "<6>[    0.581313] ACPI: Interpreter disabled."}
- {"dt": "2024-01-25T11:19:32.713530", "lvl": "target", "msg": "<6>[    0.602823] iommu: Default domain type: Translated"}
- {"dt": "2024-01-25T11:19:32.713760", "lvl": "target", "msg": "<6>[    0.603028] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-01-25T11:19:32.718426", "lvl": "target", "msg": "<5>[    0.607694] SCSI subsystem initialized"}
- {"dt": "2024-01-25T11:19:32.719959", "lvl": "target", "msg": "<7>[    0.609268] libata version 3.00 loaded."}
- {"dt": "2024-01-25T11:19:32.721956", "lvl": "target", "msg": "<6>[    0.611273] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-01-25T11:19:32.722257", "lvl": "target", "msg": "<6>[    0.611677] usbcore: registered new interface driver hub"}
- {"dt": "2024-01-25T11:19:32.722635", "lvl": "target", "msg": "<6>[    0.612064] usbcore: registered new device driver usb"}
- {"dt": "2024-01-25T11:19:32.728427", "lvl": "target", "msg": "<6>[    0.617728] mc: Linux media interface: v0.10"}
- {"dt": "2024-01-25T11:19:32.728759", "lvl": "target", "msg": "<6>[    0.618156] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-01-25T11:19:32.729325", "lvl": "target", "msg": "<6>[    0.618626] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-01-25T11:19:32.729468", "lvl": "target", "msg": "<6>[    0.618802] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-01-25T11:19:32.729771", "lvl": "target", "msg": "<6>[    0.619177] PTP clock support registered"}
- {"dt": "2024-01-25T11:19:32.731914", "lvl": "target", "msg": "<6>[    0.621224] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-01-25T11:19:32.736040", "lvl": "target", "msg": "<6>[    0.625337] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-01-25T11:19:32.743060", "lvl": "target", "msg": "<6>[    0.632370] FPGA manager framework"}
- {"dt": "2024-01-25T11:19:32.743977", "lvl": "target", "msg": "<6>[    0.633291] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-01-25T11:19:32.753705", "lvl": "target", "msg": "<6>[    0.643013] vgaarb: loaded"}
- {"dt": "2024-01-25T11:19:32.760715", "lvl": "target", "msg": "<6>[    0.650016] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-01-25T11:19:33.239167", "lvl": "target", "msg": "<5>[    1.128354] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-01-25T11:19:33.239668", "lvl": "target", "msg": "<6>[    1.128982] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-01-25T11:19:33.242188", "lvl": "target", "msg": "<6>[    1.131482] pnp: PnP ACPI: disabled"}
- {"dt": "2024-01-25T11:19:33.290698", "lvl": "target", "msg": "<6>[    1.179986] NET: Registered PF_INET protocol family"}
- {"dt": "2024-01-25T11:19:33.292521", "lvl": "target", "msg": "<6>[    1.181792] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.297995", "lvl": "target", "msg": "<6>[    1.187270] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.298379", "lvl": "target", "msg": "<6>[    1.187698] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.298862", "lvl": "target", "msg": "<6>[    1.188129] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.299499", "lvl": "target", "msg": "<6>[    1.188791] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.300571", "lvl": "target", "msg": "<6>[    1.189867] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-01-25T11:19:33.303176", "lvl": "target", "msg": "<6>[    1.192457] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.303815", "lvl": "target", "msg": "<6>[    1.193118] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.304434", "lvl": "target", "msg": "<6>[    1.193723] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-01-25T11:19:33.305774", "lvl": "target", "msg": "<6>[    1.195069] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-01-25T11:19:33.309857", "lvl": "target", "msg": "<6>[    1.199150] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-01-25T11:19:33.310061", "lvl": "target", "msg": "<6>[    1.199405] RPC: Registered udp transport module."}
- {"dt": "2024-01-25T11:19:33.310384", "lvl": "target", "msg": "<6>[    1.199695] RPC: Registered tcp transport module."}
- {"dt": "2024-01-25T11:19:33.310608", "lvl": "target", "msg": "<6>[    1.199906] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-01-25T11:19:33.310935", "lvl": "target", "msg": "<6>[    1.200240] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-01-25T11:19:33.311255", "lvl": "target", "msg": "<6>[    1.200661] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-01-25T11:19:33.313266", "lvl": "target", "msg": "<6>[    1.202554] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-01-25T11:19:33.314161", "lvl": "target", "msg": "<6>[    1.203464] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-01-25T11:19:33.314428", "lvl": "target", "msg": "<6>[    1.203764] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-01-25T11:19:33.315067", "lvl": "target", "msg": "<6>[    1.204365] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-01-25T11:19:33.316100", "lvl": "target", "msg": "<6>[    1.205401] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-01-25T11:19:33.317162", "lvl": "target", "msg": "<6>[    1.206344] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-01-25T11:19:33.324992", "lvl": "target", "msg": "<5>[    1.214221] Initialise system trusted keyrings"}
- {"dt": "2024-01-25T11:19:33.327332", "lvl": "target", "msg": "<6>[    1.216438] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-01-25T11:19:33.331674", "lvl": "target", "msg": "<6>[    1.220861] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-01-25T11:19:33.335832", "lvl": "target", "msg": "<5>[    1.225078] NFS: Registering the id_resolver key type"}
- {"dt": "2024-01-25T11:19:33.336293", "lvl": "target", "msg": "<5>[    1.225501] Key type id_resolver registered"}
- {"dt": "2024-01-25T11:19:33.336430", "lvl": "target", "msg": "<5>[    1.225842] Key type id_legacy registered"}
- {"dt": "2024-01-25T11:19:33.336887", "lvl": "target", "msg": "<6>[    1.226262] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-01-25T11:19:33.337202", "lvl": "target", "msg": "<6>[    1.226553] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-01-25T11:19:33.338502", "lvl": "target", "msg": "<6>[    1.227780] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-01-25T11:19:33.406507", "lvl": "target", "msg": "<6>[    1.295733] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-01-25T11:19:33.406658", "lvl": "target", "msg": "<5>[    1.296069] Key type asymmetric registered"}
- {"dt": "2024-01-25T11:19:33.406840", "lvl": "target", "msg": "<5>[    1.296273] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-01-25T11:19:33.407429", "lvl": "target", "msg": "<6>[    1.296776] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)"}
- {"dt": "2024-01-25T11:19:33.407677", "lvl": "target", "msg": "<6>[    1.297112] io scheduler mq-deadline registered"}
- {"dt": "2024-01-25T11:19:33.407865", "lvl": "target", "msg": "<6>[    1.297322] io scheduler kyber registered"}
- {"dt": "2024-01-25T11:19:33.485926", "lvl": "target", "msg": "<6>[    1.375080] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-01-25T11:19:33.500823", "lvl": "target", "msg": "<6>[    1.390034] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-01-25T11:19:33.501657", "lvl": "target", "msg": "<6>[    1.390829] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-01-25T11:19:33.502254", "lvl": "target", "msg": "<6>[    1.391565] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-01-25T11:19:33.502633", "lvl": "target", "msg": "<6>[    1.391950] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-01-25T11:19:33.503385", "lvl": "target", "msg": "<4>[    1.392662] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-01-25T11:19:33.504293", "lvl": "target", "msg": "<6>[    1.393379] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-01-25T11:19:33.505831", "lvl": "target", "msg": "<6>[    1.395110] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-01-25T11:19:33.506076", "lvl": "target", "msg": "<6>[    1.395481] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-01-25T11:19:33.506350", "lvl": "target", "msg": "<6>[    1.395715] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-01-25T11:19:33.506570", "lvl": "target", "msg": "<6>[    1.395948] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-01-25T11:19:33.506794", "lvl": "target", "msg": "<6>[    1.396168] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-01-25T11:19:33.508208", "lvl": "target", "msg": "<6>[    1.397482] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-01-25T11:19:33.529133", "lvl": "target", "msg": "<6>[    1.418328] EINJ: ACPI disabled."}
- {"dt": "2024-01-25T11:19:33.772667", "lvl": "target", "msg": "<6>[    1.661862] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-01-25T11:19:33.795341", "lvl": "target", "msg": "<6>[    1.684604] msm_serial: driver initialized"}
- {"dt": "2024-01-25T11:19:33.797685", "lvl": "target", "msg": "<6>[    1.686939] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-01-25T11:19:33.798601", "lvl": "target", "msg": "<6>[    1.687865] STM32 USART driver initialized"}
- {"dt": "2024-01-25T11:19:33.860849", "lvl": "target", "msg": "<6>[    1.750033] loop: module loaded"}
- {"dt": "2024-01-25T11:19:33.862817", "lvl": "target", "msg": "<6>[    1.751993] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-01-25T11:19:33.871450", "lvl": "target", "msg": "<5>[    1.760500] virtio_blk virtio0: [vda] 5480384 512-byte logical blocks (2.81 GB/2.61 GiB)"}
- {"dt": "2024-01-25T11:19:33.907892", "lvl": "target", "msg": "<6>[    1.797093] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-01-25T11:19:33.924440", "lvl": "target", "msg": "<5>[    1.813585] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-01-25T11:19:33.925851", "lvl": "target", "msg": "<6>[    1.815056] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-01-25T11:19:33.926264", "lvl": "target", "msg": "<6>[    1.815659] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-01-25T11:19:33.927131", "lvl": "target", "msg": "<6>[    1.816397] Using buffer write method"}
- {"dt": "2024-01-25T11:19:33.927407", "lvl": "target", "msg": "<7>[    1.816802] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-01-25T11:19:33.927839", "lvl": "target", "msg": "<5>[    1.817180] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-01-25T11:19:33.928768", "lvl": "target", "msg": "<6>[    1.817996] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-01-25T11:19:33.928958", "lvl": "target", "msg": "<6>[    1.818382] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-01-25T11:19:33.929684", "lvl": "target", "msg": "<6>[    1.818959] Using buffer write method"}
- {"dt": "2024-01-25T11:19:33.929799", "lvl": "target", "msg": "<7>[    1.819111] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-01-25T11:19:33.929901", "lvl": "target", "msg": "<5>[    1.819342] Concatenating MTD devices:"}
- {"dt": "2024-01-25T11:19:33.930008", "lvl": "target", "msg": "<5>[    1.819491] (0): \\\"0.flash\\\""}
- {"dt": "2024-01-25T11:19:33.930123", "lvl": "target", "msg": "<5>[    1.819617] (1): \\\"0.flash\\\""}
- {"dt": "2024-01-25T11:19:33.930251", "lvl": "target", "msg": "<5>[    1.819727] into device \\\"0.flash\\\""}
- {"dt": "2024-01-25T11:19:33.984877", "lvl": "target", "msg": "<6>[    1.874104] thunder_xcv, ver 1.0"}
- {"dt": "2024-01-25T11:19:33.985028", "lvl": "target", "msg": "<6>[    1.874378] thunder_bgx, ver 1.0"}
- {"dt": "2024-01-25T11:19:33.985159", "lvl": "target", "msg": "<6>[    1.874636] nicpf, ver 1.0"}
- {"dt": "2024-01-25T11:19:33.993240", "lvl": "target", "msg": "<6>[    1.882472] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-01-25T11:19:33.993394", "lvl": "target", "msg": "<6>[    1.882701] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-01-25T11:19:33.993785", "lvl": "target", "msg": "<6>[    1.883129] hclge is initializing"}
- {"dt": "2024-01-25T11:19:33.993950", "lvl": "target", "msg": "<6>[    1.883382] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-01-25T11:19:33.994137", "lvl": "target", "msg": "<6>[    1.883555] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-01-25T11:19:33.994513", "lvl": "target", "msg": "<6>[    1.883879] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-01-25T11:19:33.994626", "lvl": "target", "msg": "<6>[    1.884046] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-01-25T11:19:33.995011", "lvl": "target", "msg": "<6>[    1.884356] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-01-25T11:19:33.995131", "lvl": "target", "msg": "<6>[    1.884547] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-01-25T11:19:33.995494", "lvl": "target", "msg": "<6>[    1.884870] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-01-25T11:19:33.995654", "lvl": "target", "msg": "<6>[    1.885073] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-01-25T11:19:33.998086", "lvl": "target", "msg": "<6>[    1.887369] sky2: driver version 1.30"}
- {"dt": "2024-01-25T11:19:33.998208", "lvl": "target", "msg": "<6>[    1.887640] QLogic FastLinQ 4xxxx Core Module qed"}
- {"dt": "2024-01-25T11:19:33.998559", "lvl": "target", "msg": "<6>[    1.887877] qede init: QLogic FastLinQ 4xxxx Ethernet Driver qede"}
- {"dt": "2024-01-25T11:19:34.007010", "lvl": "target", "msg": "<6>[    1.896292] usbcore: registered new interface driver asix"}
- {"dt": "2024-01-25T11:19:34.007204", "lvl": "target", "msg": "<6>[    1.896604] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2024-01-25T11:19:34.009967", "lvl": "target", "msg": "<6>[    1.899150] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-01-25T11:19:34.050599", "lvl": "target", "msg": "<6>[    1.939772] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-01-25T11:19:34.081736", "lvl": "target", "msg": "<6>[    1.970964] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-01-25T11:19:34.082588", "lvl": "target", "msg": "<6>[    1.971610] rtc-pl031 9010000.pl031: setting system clock to 2024-01-25T11:19:34 UTC (1706181574)"}
- {"dt": "2024-01-25T11:19:34.087012", "lvl": "target", "msg": "<6>[    1.976310] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-01-25T11:19:34.132948", "lvl": "target", "msg": "<6>[    2.022214] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-01-25T11:19:34.133076", "lvl": "target", "msg": "<6>[    2.022423] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-01-25T11:19:34.137292", "lvl": "target", "msg": "<6>[    2.026573] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-01-25T11:19:34.143319", "lvl": "target", "msg": "<6>[    2.032597] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-01-25T11:19:34.155557", "lvl": "target", "msg": "<6>[    2.044834] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-01-25T11:19:34.179345", "lvl": "target", "msg": "<6>[    2.068617] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-01-25T11:19:34.179465", "lvl": "target", "msg": "<6>[    2.068831] usbhid: USB HID core driver"}
- {"dt": "2024-01-25T11:19:34.199011", "lvl": "target", "msg": "<6>[    2.088315] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available"}
- {"dt": "2024-01-25T11:19:34.206090", "lvl": "target", "msg": "<6>[    2.095335]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2024-01-25T11:19:34.233489", "lvl": "target", "msg": "<6>[    2.122757] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-01-25T11:19:34.243936", "lvl": "target", "msg": "<6>[    2.133207] Segment Routing with IPv6"}
- {"dt": "2024-01-25T11:19:34.244061", "lvl": "target", "msg": "<6>[    2.133491] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-01-25T11:19:34.244949", "lvl": "target", "msg": "<6>[    2.134231] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-01-25T11:19:34.246112", "lvl": "target", "msg": "<6>[    2.135397] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-01-25T11:19:34.246485", "lvl": "target", "msg": "<5>[    2.135832] Key type dns_resolver registered"}
- {"dt": "2024-01-25T11:19:34.296887", "lvl": "target", "msg": "<6>[    2.186161] registered taskstats version 1"}
- {"dt": "2024-01-25T11:19:34.298052", "lvl": "target", "msg": "<5>[    2.187340] Loading compiled-in X.509 certificates"}
- {"dt": "2024-01-25T11:19:34.349968", "lvl": "target", "msg": "<6>[    2.239217] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-01-25T11:19:34.358914", "lvl": "target", "msg": "<6>[    2.248119] clk: Disabling unused clocks"}
- {"dt": "2024-01-25T11:19:34.359977", "lvl": "target", "msg": "<6>[    2.249207] ALSA device list:"}
- {"dt": "2024-01-25T11:19:34.360306", "lvl": "target", "msg": "<6>[    2.249572]   No soundcards found."}
- {"dt": "2024-01-25T11:19:34.415995", "lvl": "target", "msg": "<6>[    2.305122] EXT4-fs (vda): mounted filesystem bc85a55f-3ba4-4c03-81d5-f205be2e5ff5 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-01-25T11:19:34.417493", "lvl": "target", "msg": "<6>[    2.306768] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-01-25T11:19:34.419483", "lvl": "target", "msg": "<6>[    2.308756] devtmpfs: mounted"}
- {"dt": "2024-01-25T11:19:34.475358", "lvl": "target", "msg": "<6>[    2.364559] Freeing unused kernel memory: 14400K"}
- {"dt": "2024-01-25T11:19:34.476718", "lvl": "target", "msg": "<6>[    2.365969] Run /sbin/init as init process"}
- {"dt": "2024-01-25T11:19:34.476849", "lvl": "target", "msg": "<7>[    2.366195]   with arguments:"}
- {"dt": "2024-01-25T11:19:34.476965", "lvl": "target", "msg": "<7>[    2.366346]     /sbin/init"}
- {"dt": "2024-01-25T11:19:34.477092", "lvl": "target", "msg": "<7>[    2.366464]     verbose"}
- {"dt": "2024-01-25T11:19:34.477182", "lvl": "target", "msg": "<7>[    2.366570]   with environment:"}
- {"dt": "2024-01-25T11:19:34.477285", "lvl": "target", "msg": "<7>[    2.366689]     HOME=/"}
- {"dt": "2024-01-25T11:19:34.477413", "lvl": "target", "msg": "<7>[    2.366793]     TERM=linux"}
- {"dt": "2024-01-25T11:19:35.032001", "lvl": "target", "msg": ""}
- {"dt": "2024-01-25T11:19:35.032493", "lvl": "target", "msg": "Welcome to [1mDebian GNU/Linux 12 (bookworm)[0m!"}
- {"dt": "2024-01-25T11:19:35.032606", "lvl": "target", "msg": ""}
- {"dt": "2024-01-25T11:19:35.610257", "lvl": "target", "msg": "<28>[    3.498541] systemd-fstab-generator[81]: x-systemd.device-timeout ignored for v_tmp"}
- {"dt": "2024-01-25T11:19:36.651984", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty."}
- {"dt": "2024-01-25T11:19:36.659290", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modpr\u2026lice[0m - Slice /system/modprobe."}
- {"dt": "2024-01-25T11:19:36.665131", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-seria\u2026[0m - Slice /system/serial-getty."}
- {"dt": "2024-01-25T11:19:36.670979", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-syste\u2026[0m - Slice /system/systemd-fsck."}
- {"dt": "2024-01-25T11:19:36.677197", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-telne\u2026[0m - Slice /system/telnet-getty."}
- {"dt": "2024-01-25T11:19:36.682346", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice."}
- {"dt": "2024-01-25T11:19:36.685535", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026quests to Console Directory Watch."}
- {"dt": "2024-01-25T11:19:36.687817", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026 Requests to Wall Directory Watch."}
- {"dt": "2024-01-25T11:19:36.691520", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mcryptsetup.\u2026get[0m - Local Encrypted Volumes."}
- {"dt": "2024-01-25T11:19:36.692519", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mintegrityse\u2026Local Integrity Protected Volumes."}
- {"dt": "2024-01-25T11:19:36.693489", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnss-lookup.\u2026m - Host and Network Name Lookups."}
- {"dt": "2024-01-25T11:19:36.694253", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units."}
- {"dt": "2024-01-25T11:19:36.695018", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units."}
- {"dt": "2024-01-25T11:19:36.695741", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps."}
- {"dt": "2024-01-25T11:19:36.696849", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mveritysetup\u2026 - Local Verity Protected Volumes."}
- {"dt": "2024-01-25T11:19:36.700445", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdm-event.sock\u2026 Device-mapper event daemon FIFOs."}
- {"dt": "2024-01-25T11:19:36.704067", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mlvm2-lvmpolld\u2026ket[0m - LVM2 poll daemon socket."}
- {"dt": "2024-01-25T11:19:36.939751", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mrpcbind.socke\u2026 RPCbind Server Activation Socket."}
- {"dt": "2024-01-25T11:19:36.948008", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msyslog.socket[0m - Syslog Socket."}
- {"dt": "2024-01-25T11:19:36.954628", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-fsckd\u2026sck to fsckd communication Socket."}
- {"dt": "2024-01-25T11:19:36.959281", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initc\u2026 initctl Compatibility Named Pipe."}
- {"dt": "2024-01-25T11:19:36.968691", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026socket[0m - Journal Audit Socket."}
- {"dt": "2024-01-25T11:19:36.977062", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026t[0m - Journal Socket (/dev/log)."}
- {"dt": "2024-01-25T11:19:36.985426", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket."}
- {"dt": "2024-01-25T11:19:36.992525", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026.socket[0m - udev Control Socket."}
- {"dt": "2024-01-25T11:19:36.997946", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026l.socket[0m - udev Kernel Socket."}
- {"dt": "2024-01-25T11:19:37.043411", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System..."}
- {"dt": "2024-01-25T11:19:37.062107", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount\u2026POSIX Message Queue File System..."}
- {"dt": "2024-01-25T11:19:37.082591", "lvl": "target", "msg": "         Mounting [0;1;39mproc-fs-nfsd.moun\u2026- NFSD configuration filesystem..."}
- {"dt": "2024-01-25T11:19:37.138661", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - /sys/kernel/debug..."}
- {"dt": "2024-01-25T11:19:37.206499", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracin\u2026[0m - Kernel Trace File System..."}
- {"dt": "2024-01-25T11:19:37.211502", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mblk-availability.\u2026m - Availability of block devices."}
- {"dt": "2024-01-25T11:19:37.267621", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes\u2026ate List of Static Device Nodes..."}
- {"dt": "2024-01-25T11:19:37.285731", "lvl": "target", "msg": "         Starting [0;1;39mlvm2-monitor.serv\u2026ng dmeventd or progress polling..."}
- {"dt": "2024-01-25T11:19:37.313894", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs\u2026m - Load Kernel Module configfs..."}
- {"dt": "2024-01-25T11:19:37.345618", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@dm_mod.s\u2026[0m - Load Kernel Module dm_mod..."}
- {"dt": "2024-01-25T11:19:37.398995", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm..."}
- {"dt": "2024-01-25T11:19:37.458869", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_psto\u2026- Load Kernel Module efi_pstore..."}
- {"dt": "2024-01-25T11:19:37.464721", "lvl": "target", "msg": "<6>[    5.353886] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com"}
- {"dt": "2024-01-25T11:19:37.526618", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.ser\u2026e[0m - Load Kernel Module fuse..."}
- {"dt": "2024-01-25T11:19:37.584096", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@loop.ser\u2026e[0m - Load Kernel Module loop..."}
- {"dt": "2024-01-25T11:19:37.619102", "lvl": "target", "msg": "<6>[    5.508335] fuse: init (API version 7.39)"}
- {"dt": "2024-01-25T11:19:37.667993", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service..."}
- {"dt": "2024-01-25T11:19:37.742561", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-l\u2026rvice[0m - Load Kernel Modules..."}
- {"dt": "2024-01-25T11:19:37.770738", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-f\u2026nt Root and Kernel File Systems..."}
- {"dt": "2024-01-25T11:19:37.819466", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trig\u2026[0m - Coldplug All udev Devices..."}
- {"dt": "2024-01-25T11:19:37.926765", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System."}
- {"dt": "2024-01-25T11:19:37.940044", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[\u2026- POSIX Message Queue File System."}
- {"dt": "2024-01-25T11:19:37.947624", "lvl": "target", "msg": "<28>[    5.836903] systemd[1]: proc-fs-nfsd.mount: Failed with result 'exit-code'."}
- {"dt": "2024-01-25T11:19:37.964390", "lvl": "target", "msg": "<44>[    5.853420] systemd-journald[110]: /etc/systemd/journald.conf.d/99-gce-xfstests.conf:4: Unknown key name 'ForwardToKmsg' in section 'Journal', ignoring."}
- {"dt": "2024-01-25T11:19:37.972704", "lvl": "target", "msg": "<27>[    5.861996] systemd[1]: Failed to mount proc-fs-nfsd.mount - NFSD configuration filesystem."}
- {"dt": "2024-01-25T11:19:37.973589", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mproc-fs-nf\u2026m - NFSD configuration filesystem."}
- {"dt": "2024-01-25T11:19:37.974079", "lvl": "target", "msg": "See 'systemctl status proc-fs-nfsd.mount' for details."}
- {"dt": "2024-01-25T11:19:37.974875", "lvl": "target", "msg": "<28>[    5.863992] systemd[1]: Dependency failed for nfsdcld.service - NFSv4 Client Tracking Daemon."}
- {"dt": "2024-01-25T11:19:37.975386", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mnfsd\u20260m - NFSv4 Client Tracking Daemon."}
- {"dt": "2024-01-25T11:19:37.976260", "lvl": "target", "msg": "<28>[    5.865400] systemd[1]: Dependency failed for nfs-mountd.service - NFS Mount Daemon."}
- {"dt": "2024-01-25T11:19:37.984221", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mnfs-\u2026td.service[0m - NFS Mount Daemon."}
- {"dt": "2024-01-25T11:19:37.985674", "lvl": "target", "msg": "<28>[    5.874796] systemd[1]: Dependency failed for nfs-server.service - NFS server and services."}
- {"dt": "2024-01-25T11:19:37.986227", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mnfs-\u2026ice[0m - NFS server and services."}
- {"dt": "2024-01-25T11:19:37.986960", "lvl": "target", "msg": "<28>[    5.876244] systemd[1]: Dependency failed for nfs-idmapd.service - NFSv4 ID-name mapping service."}
- {"dt": "2024-01-25T11:19:37.987760", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mnfs-\u2026m - NFSv4 ID-name mapping service."}
- {"dt": "2024-01-25T11:19:37.998581", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - /sys/kernel/debug."}
- {"dt": "2024-01-25T11:19:38.006829", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing\u2026nt[0m - Kernel Trace File System."}
- {"dt": "2024-01-25T11:19:38.018713", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes\u2026reate List of Static Device Nodes."}
- {"dt": "2024-01-25T11:19:38.035784", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mlvm2-monitor.serv\u2026sing dmeventd or progress polling."}
- {"dt": "2024-01-25T11:19:38.061378", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs\u2026[0m - Load Kernel Module configfs."}
- {"dt": "2024-01-25T11:19:38.083268", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@dm_mod.s\u2026e[0m - Load Kernel Module dm_mod."}
- {"dt": "2024-01-25T11:19:38.111580", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm."}
- {"dt": "2024-01-25T11:19:38.134522", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_psto\u2026m - Load Kernel Module efi_pstore."}
- {"dt": "2024-01-25T11:19:38.145124", "lvl": "target", "msg": "<6>[    6.034325] EXT4-fs (vda): re-mounted bc85a55f-3ba4-4c03-81d5-f205be2e5ff5 r/w. Quota mode: none."}
- {"dt": "2024-01-25T11:19:38.185331", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse."}
- {"dt": "2024-01-25T11:19:38.197685", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop."}
- {"dt": "2024-01-25T11:19:38.203691", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-l\u2026service[0m - Load Kernel Modules."}
- {"dt": "2024-01-25T11:19:38.216958", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-f\u2026ount Root and Kernel File Systems."}
- {"dt": "2024-01-25T11:19:38.273847", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-conne\u2026[0m - FUSE Control File System..."}
- {"dt": "2024-01-25T11:19:38.346080", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config\u2026ernel Configuration File System..."}
- {"dt": "2024-01-25T11:19:38.450787", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-se\u2026ice[0m - Load/Save Random Seed..."}
- {"dt": "2024-01-25T11:19:38.498758", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.se\u2026ce[0m - Apply Kernel Variables..."}
- {"dt": "2024-01-25T11:19:38.562945", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.\u2026rvice[0m - Create System Users..."}
- {"dt": "2024-01-25T11:19:38.660335", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service."}
- {"dt": "2024-01-25T11:19:38.715430", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connec\u2026nt[0m - FUSE Control File System."}
- {"dt": "2024-01-25T11:19:38.722868", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.\u2026 Kernel Configuration File System."}
- {"dt": "2024-01-25T11:19:38.741501", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-se\u2026rvice[0m - Load/Save Random Seed."}
- {"dt": "2024-01-25T11:19:38.839331", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-f\u2026h Journal to Persistent Storage..."}
- {"dt": "2024-01-25T11:19:38.861076", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables."}
- {"dt": "2024-01-25T11:19:38.879206", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users."}
- {"dt": "2024-01-25T11:19:38.942969", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026ate Static Device Nodes in /dev..."}
- {"dt": "2024-01-25T11:19:39.149985", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-f\u2026ush Journal to Persistent Storage."}
- {"dt": "2024-01-25T11:19:39.193917", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026reate Static Device Nodes in /dev."}
- {"dt": "2024-01-25T11:19:39.197211", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pr\u2026reparation for Local File Systems."}
- {"dt": "2024-01-25T11:19:39.246891", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - /tmp..."}
- {"dt": "2024-01-25T11:19:39.288126", "lvl": "target", "msg": "         Mounting [0;1;39mvtmp.mount[0m - /vtmp..."}
- {"dt": "2024-01-25T11:19:39.327290", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.ser\u2026ger for Device Events and Files..."}
- {"dt": "2024-01-25T11:19:39.378716", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - /tmp."}
- {"dt": "2024-01-25T11:19:39.399718", "lvl": "target", "msg": "<3>[    7.288892] 9pnet_virtio: no channels available for device v_tmp"}
- {"dt": "2024-01-25T11:19:39.450136", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mvtmp.mount[0m - /vtmp."}
- {"dt": "2024-01-25T11:19:39.457423", "lvl": "target", "msg": "See 'systemctl status vtmp.mount' for details."}
- {"dt": "2024-01-25T11:19:39.753914", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.serv\u2026nager for Device Events and Files."}
- {"dt": "2024-01-25T11:19:41.761901", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trig\u2026e[0m - Coldplug All udev Devices."}
- {"dt": "2024-01-25T11:19:41.827048", "lvl": "target", "msg": "         Starting [0;1;39mifupdown-pre.serv\u2026ynchronize boot up for ifupdown..."}
- {"dt": "2024-01-25T11:19:41.914021", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mifupdown-pre.serv\u2026 synchronize boot up for ifupdown."}
- {"dt": "2024-01-25T11:19:42.091593", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0."}
- {"dt": "2024-01-25T11:19:42.149833", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyS1.device[0m - /dev/ttyS1."}
- {"dt": "2024-01-25T11:19:42.165735", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyS2.device[0m - /dev/ttyS2."}
- {"dt": "2024-01-25T11:19:42.178968", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyS3.device[0m - /dev/ttyS3."}
- {"dt": "2024-01-25T11:19:44.433600", "lvl": "target", "msg": "[[0m[0;31m*     [0m] Job dev-vdg.device/start running (7s / no limit)"}
- {"dt": "2024-01-25T11:19:45.016647", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (8s / no limit)"}
- {"dt": "2024-01-25T11:19:45.600004", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (9s / no limit)"}
- {"dt": "2024-01-25T11:19:46.183519", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (9s / no limit)"}
- {"dt": "2024-01-25T11:19:46.767167", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (10s / no limit)"}
- {"dt": "2024-01-25T11:19:47.351583", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (10s / no limit)"}
- {"dt": "2024-01-25T11:19:47.935694", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (11s / no limit)"}
- {"dt": "2024-01-25T11:19:48.518652", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (11s / no limit)"}
- {"dt": "2024-01-25T11:19:49.103047", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (12s / no limit)"}
- {"dt": "2024-01-25T11:19:49.687044", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (13s / no limit)"}
- {"dt": "2024-01-25T11:19:50.270752", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (13s / no limit)"}
- {"dt": "2024-01-25T11:19:50.854613", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (14s / no limit)"}
- {"dt": "2024-01-25T11:19:51.438426", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (14s / no limit)"}
- {"dt": "2024-01-25T11:19:52.022313", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (15s / no limit)"}
- {"dt": "2024-01-25T11:19:52.605667", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (16s / no limit)"}
- {"dt": "2024-01-25T11:19:53.190151", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (16s / no limit)"}
- {"dt": "2024-01-25T11:19:53.773199", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (17s / no limit)"}
- {"dt": "2024-01-25T11:19:54.357593", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (17s / no limit)"}
- {"dt": "2024-01-25T11:19:54.941361", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (18s / no limit)"}
- {"dt": "2024-01-25T11:19:55.525104", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (18s / no limit)"}
- {"dt": "2024-01-25T11:19:56.109213", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (19s / no limit)"}
- {"dt": "2024-01-25T11:19:56.692978", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (20s / no limit)"}
- {"dt": "2024-01-25T11:19:57.276779", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (20s / no limit)"}
- {"dt": "2024-01-25T11:19:57.860654", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (21s / no limit)"}
- {"dt": "2024-01-25T11:19:58.445058", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (21s / no limit)"}
- {"dt": "2024-01-25T11:19:59.028767", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (22s / no limit)"}
- {"dt": "2024-01-25T11:19:59.611215", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (23s / no limit)"}
- {"dt": "2024-01-25T11:20:00.196675", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (23s / no limit)"}
- {"dt": "2024-01-25T11:20:00.780308", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (24s / no limit)"}
- {"dt": "2024-01-25T11:20:01.363016", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (24s / no limit)"}
- {"dt": "2024-01-25T11:20:01.947302", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (25s / no limit)"}
- {"dt": "2024-01-25T11:20:02.531034", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (25s / no limit)"}
- {"dt": "2024-01-25T11:20:03.114851", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (26s / no limit)"}
- {"dt": "2024-01-25T11:20:03.698492", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (27s / no limit)"}
- {"dt": "2024-01-25T11:20:04.282517", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (27s / no limit)"}
- {"dt": "2024-01-25T11:20:04.866148", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (28s / no limit)"}
- {"dt": "2024-01-25T11:20:05.450165", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (28s / no limit)"}
- {"dt": "2024-01-25T11:20:06.034220", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (29s / no limit)"}
- {"dt": "2024-01-25T11:20:06.617904", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (30s / no limit)"}
- {"dt": "2024-01-25T11:20:07.202409", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (30s / no limit)"}
- {"dt": "2024-01-25T11:20:07.786230", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (31s / no limit)"}
- {"dt": "2024-01-25T11:20:08.370328", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (31s / no limit)"}
- {"dt": "2024-01-25T11:20:08.954184", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (32s / no limit)"}
- {"dt": "2024-01-25T11:20:09.538403", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (32s / no limit)"}
- {"dt": "2024-01-25T11:20:09.985058", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (33s / no limit)"}
- {"dt": "2024-01-25T11:20:10.484785", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (33s / no limit)"}
- {"dt": "2024-01-25T11:20:10.984612", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (34s / no limit)"}
- {"dt": "2024-01-25T11:20:11.485034", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (34s / no limit)"}
- {"dt": "2024-01-25T11:20:11.984821", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (35s / no limit)"}
- {"dt": "2024-01-25T11:20:12.484503", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (35s / no limit)"}
- {"dt": "2024-01-25T11:20:12.984564", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (36s / no limit)"}
- {"dt": "2024-01-25T11:20:13.484739", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (36s / no limit)"}
- {"dt": "2024-01-25T11:20:13.984880", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (37s / no limit)"}
- {"dt": "2024-01-25T11:20:14.485224", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (37s / no limit)"}
- {"dt": "2024-01-25T11:20:14.984868", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (38s / no limit)"}
- {"dt": "2024-01-25T11:20:15.484618", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (38s / no limit)"}
- {"dt": "2024-01-25T11:20:15.985153", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (39s / no limit)"}
- {"dt": "2024-01-25T11:20:16.484630", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (39s / no limit)"}
- {"dt": "2024-01-25T11:20:16.985115", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (40s / no limit)"}
- {"dt": "2024-01-25T11:20:17.485224", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (40s / no limit)"}
- {"dt": "2024-01-25T11:20:17.984619", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (41s / no limit)"}
- {"dt": "2024-01-25T11:20:18.484718", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (41s / no limit)"}
- {"dt": "2024-01-25T11:20:18.984597", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (42s / no limit)"}
- {"dt": "2024-01-25T11:20:19.484006", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (42s / no limit)"}
- {"dt": "2024-01-25T11:20:19.984741", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (43s / no limit)"}
- {"dt": "2024-01-25T11:20:20.484752", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (43s / no limit)"}
- {"dt": "2024-01-25T11:20:20.985064", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (44s / no limit)"}
- {"dt": "2024-01-25T11:20:21.485076", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (44s / no limit)"}
- {"dt": "2024-01-25T11:20:21.985021", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (45s / no limit)"}
- {"dt": "2024-01-25T11:20:22.484740", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (45s / no limit)"}
- {"dt": "2024-01-25T11:20:22.984939", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (46s / no limit)"}
- {"dt": "2024-01-25T11:20:23.484653", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (46s / no limit)"}
- {"dt": "2024-01-25T11:20:23.984721", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (47s / no limit)"}
- {"dt": "2024-01-25T11:20:24.483967", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (47s / no limit)"}
- {"dt": "2024-01-25T11:20:24.985018", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (48s / no limit)"}
- {"dt": "2024-01-25T11:20:25.485250", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (48s / no limit)"}
- {"dt": "2024-01-25T11:20:25.984943", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (49s / no limit)"}
- {"dt": "2024-01-25T11:20:26.484660", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (49s / no limit)"}
- {"dt": "2024-01-25T11:20:26.984893", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (50s / no limit)"}
- {"dt": "2024-01-25T11:20:27.484925", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (50s / no limit)"}
- {"dt": "2024-01-25T11:20:27.985220", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (51s / no limit)"}
- {"dt": "2024-01-25T11:20:28.484692", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (51s / no limit)"}
- {"dt": "2024-01-25T11:20:28.984680", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (52s / no limit)"}
- {"dt": "2024-01-25T11:20:29.484997", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (52s / no limit)"}
- {"dt": "2024-01-25T11:20:29.985197", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (53s / no limit)"}
- {"dt": "2024-01-25T11:20:30.485149", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (53s / no limit)"}
- {"dt": "2024-01-25T11:20:30.984901", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (54s / no limit)"}
- {"dt": "2024-01-25T11:20:31.485356", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (54s / no limit)"}
- {"dt": "2024-01-25T11:20:31.984829", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (55s / no limit)"}
- {"dt": "2024-01-25T11:20:32.485185", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (55s / no limit)"}
- {"dt": "2024-01-25T11:20:32.985070", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (56s / no limit)"}
- {"dt": "2024-01-25T11:20:33.484978", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (56s / no limit)"}
- {"dt": "2024-01-25T11:20:33.984712", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (57s / no limit)"}
- {"dt": "2024-01-25T11:20:34.484610", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (57s / no limit)"}
- {"dt": "2024-01-25T11:20:34.985188", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (58s / no limit)"}
- {"dt": "2024-01-25T11:20:35.484719", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (58s / no limit)"}
- {"dt": "2024-01-25T11:20:35.984093", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (59s / no limit)"}
- {"dt": "2024-01-25T11:20:36.483944", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (59s / no limit)"}
- {"dt": "2024-01-25T11:20:36.985041", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (1min / no limit)"}
- {"dt": "2024-01-25T11:20:37.484038", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min / no limit)"}
- {"dt": "2024-01-25T11:20:37.985092", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 1s / no limit)"}
- {"dt": "2024-01-25T11:20:38.485132", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 1s / no limit)"}
- {"dt": "2024-01-25T11:20:38.985104", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 2s / no limit)"}
- {"dt": "2024-01-25T11:20:39.484714", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 2s / no limit)"}
- {"dt": "2024-01-25T11:20:39.984040", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 3s / no limit)"}
- {"dt": "2024-01-25T11:20:40.484851", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (1min 3s / no limit)"}
- {"dt": "2024-01-25T11:20:40.984980", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 4s / no limit)"}
- {"dt": "2024-01-25T11:20:41.484718", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 4s / no limit)"}
- {"dt": "2024-01-25T11:20:41.985011", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 5s / no limit)"}
- {"dt": "2024-01-25T11:20:42.485383", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 5s / no limit)"}
- {"dt": "2024-01-25T11:20:42.985077", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 6s / no limit)"}
- {"dt": "2024-01-25T11:20:43.484709", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 6s / no limit)"}
- {"dt": "2024-01-25T11:20:43.984784", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (1min 7s / no limit)"}
- {"dt": "2024-01-25T11:20:44.484779", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 7s / no limit)"}
- {"dt": "2024-01-25T11:20:44.984761", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 8s / no limit)"}
- {"dt": "2024-01-25T11:20:45.485140", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 8s / no limit)"}
- {"dt": "2024-01-25T11:20:45.984966", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 9s / no limit)"}
- {"dt": "2024-01-25T11:20:46.484690", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 9s / no limit)"}
- {"dt": "2024-01-25T11:20:46.984792", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 10s / no limit)"}
- {"dt": "2024-01-25T11:20:47.485024", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (1min 10s / no limit)"}
- {"dt": "2024-01-25T11:20:47.985470", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 11s / no limit)"}
- {"dt": "2024-01-25T11:20:48.484951", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 11s / no limit)"}
- {"dt": "2024-01-25T11:20:48.985375", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 12s / no limit)"}
- {"dt": "2024-01-25T11:20:49.485113", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 12s / no limit)"}
- {"dt": "2024-01-25T11:20:49.984753", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 13s / no limit)"}
- {"dt": "2024-01-25T11:20:50.485908", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 13s / no limit)"}
- {"dt": "2024-01-25T11:20:50.985028", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (1min 14s / no limit)"}
- {"dt": "2024-01-25T11:20:51.485213", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 14s / no limit)"}
- {"dt": "2024-01-25T11:20:51.985145", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 15s / no limit)"}
- {"dt": "2024-01-25T11:20:52.485256", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 15s / no limit)"}
- {"dt": "2024-01-25T11:20:52.985785", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 16s / no limit)"}
- {"dt": "2024-01-25T11:20:53.485056", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 16s / no limit)"}
- {"dt": "2024-01-25T11:20:53.985199", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 17s / no limit)"}
- {"dt": "2024-01-25T11:20:54.485140", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (1min 17s / no limit)"}
- {"dt": "2024-01-25T11:20:54.985034", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 18s / no limit)"}
- {"dt": "2024-01-25T11:20:55.484016", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 18s / no limit)"}
- {"dt": "2024-01-25T11:20:55.985490", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 19s / no limit)"}
- {"dt": "2024-01-25T11:20:56.485393", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 19s / no limit)"}
- {"dt": "2024-01-25T11:20:56.985458", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 20s / no limit)"}
- {"dt": "2024-01-25T11:20:57.485224", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 20s / no limit)"}
- {"dt": "2024-01-25T11:20:57.985202", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (1min 21s / no limit)"}
- {"dt": "2024-01-25T11:20:58.485409", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 21s / no limit)"}
- {"dt": "2024-01-25T11:20:58.985471", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 22s / no limit)"}
- {"dt": "2024-01-25T11:20:59.485443", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 22s / no limit)"}
- {"dt": "2024-01-25T11:20:59.985253", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 23s / no limit)"}
- {"dt": "2024-01-25T11:21:00.483833", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 23s / no limit)"}
- {"dt": "2024-01-25T11:21:00.985382", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 24s / no limit)"}
- {"dt": "2024-01-25T11:21:01.485177", "lvl": "target", "msg": "M[K[     [0;31m*[0m] Job dev-vdg.device/start running (1min 24s / no limit)"}
- {"dt": "2024-01-25T11:21:01.985399", "lvl": "target", "msg": "M[K[    [0;31m*[0;1;31m*[0m] Job dev-vdg.device/start running (1min 25s / no limit)"}
- {"dt": "2024-01-25T11:21:02.485247", "lvl": "target", "msg": "M[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] Job dev-vdg.device/start running (1min 25s / no limit)"}
- {"dt": "2024-01-25T11:21:02.985300", "lvl": "target", "msg": "M[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] Job dev-vdg.device/start running (1min 26s / no limit)"}
- {"dt": "2024-01-25T11:21:03.485444", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 26s / no limit)"}
- {"dt": "2024-01-25T11:21:03.985271", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 27s / no limit)"}
- {"dt": "2024-01-25T11:21:04.485304", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 27s / no limit)"}
- {"dt": "2024-01-25T11:21:04.985183", "lvl": "target", "msg": "M[K[[0m[0;31m*     [0m] Job dev-vdg.device/start running (1min 28s / no limit)"}
- {"dt": "2024-01-25T11:21:05.485237", "lvl": "target", "msg": "M[K[[0;1;31m*[0m[0;31m*    [0m] Job dev-vdg.device/start running (1min 28s / no limit)"}
- {"dt": "2024-01-25T11:21:05.984055", "lvl": "target", "msg": "M[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] Job dev-vdg.device/start running (1min 29s / no limit)"}
- {"dt": "2024-01-25T11:21:06.484119", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] Job dev-vdg.device/start running (1min 29s / no limit)"}
- {"dt": "2024-01-25T11:21:06.736195", "lvl": "target", "msg": "M[K[[0;1;31m TIME [0m] Timed out waiting for device [0;1;39mdev-vdg.device[0m - /dev/vdg."}
- {"dt": "2024-01-25T11:21:06.738782", "lvl": "target", "msg": "[K[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39msyst\u2026m - File System Check on /dev/vdg."}
- {"dt": "2024-01-25T11:21:06.741324", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mresults.mount[0m - /results."}
- {"dt": "2024-01-25T11:21:06.744827", "lvl": "target", "msg": "[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mloca\u2026s.target[0m - Local File Systems."}
- {"dt": "2024-01-25T11:21:06.762893", "lvl": "target", "msg": "[[0;32m  OK  [0m] Stopped [0;1;39msystemd-ask-passwo\u2026quests to Console Directory Watch."}
- {"dt": "2024-01-25T11:21:06.771282", "lvl": "target", "msg": "[[0;32m  OK  [0m] Stopped [0;1;39msystemd-ask-passwo\u2026 Requests to Wall Directory Watch."}
- {"dt": "2024-01-25T11:21:06.775058", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units."}
- {"dt": "2024-01-25T11:21:06.829098", "lvl": "target", "msg": "         Starting [0;1;39mnetworking.service[0m - Raise network interfaces..."}
- {"dt": "2024-01-25T11:21:06.845480", "lvl": "target", "msg": "[[0;32m  OK  [0m] Closed [0;1;39msyslog.socket[0m - Syslog Socket."}
- {"dt": "2024-01-25T11:21:06.851785", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts."}
- {"dt": "2024-01-25T11:21:06.854268", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units."}
- {"dt": "2024-01-25T11:21:06.896515", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39memergency.service[0m - Emergency Shell."}
- {"dt": "2024-01-25T11:21:06.898952", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39memergency.target[0m - Emergency Mode."}
- {"dt": "2024-01-25T11:21:06.939583", "lvl": "target", "msg": "         Starting [0;1;39msystemd-binfmt.se\u2026et Up Additional Binary Formats..."}
- {"dt": "2024-01-25T11:21:06.983986", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026 Volatile Files and Directories..."}
- {"dt": "2024-01-25T11:21:07.083612", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to start [0;1;39msystemd-bi\u2026 Set Up Additional Binary Formats."}
- {"dt": "2024-01-25T11:21:07.084822", "lvl": "target", "msg": "See 'systemctl status systemd-binfmt.service' for details."}
- {"dt": "2024-01-25T11:21:07.274714", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026te Volatile Files and Directories."}
- {"dt": "2024-01-25T11:21:07.326928", "lvl": "target", "msg": "         Mounting [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System..."}
- {"dt": "2024-01-25T11:21:07.349285", "lvl": "target", "msg": "         Starting [0;1;39mrpcbind.service[0m - RPC bind portmap service..."}
- {"dt": "2024-01-25T11:21:07.373741", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026rd System Boot/Shutdown in UTMP..."}
- {"dt": "2024-01-25T11:21:07.409618", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mnetworking.service[0m - Raise network interfaces."}
- {"dt": "2024-01-25T11:21:07.437301", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System."}
- {"dt": "2024-01-25T11:21:07.445164", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork.target[0m - Network."}
- {"dt": "2024-01-25T11:21:07.449090", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online."}
- {"dt": "2024-01-25T11:21:07.454062", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpc_pipefs.target[0m."}
- {"dt": "2024-01-25T11:21:07.501282", "lvl": "target", "msg": "         Starting [0;1;39mnfs-blkmap.servic\u2026NFS block layout mapping daemon..."}
- {"dt": "2024-01-25T11:21:07.503187", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnfs-client.target[0m - NFS client services."}
- {"dt": "2024-01-25T11:21:07.522101", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd-notify.\u2026- Notify NFS peers of a restart..."}
- {"dt": "2024-01-25T11:21:07.535126", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpcbind.service[0m - RPC bind portmap service."}
- {"dt": "2024-01-25T11:21:07.555425", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mnfs-blkmap.service\u2026 pNFS block layout mapping daemon."}
- {"dt": "2024-01-25T11:21:07.595843", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd-notify.s\u2026m - Notify NFS peers of a restart."}
- {"dt": "2024-01-25T11:21:07.610871", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026cord System Boot/Shutdown in UTMP."}
- {"dt": "2024-01-25T11:21:07.614016", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs-p\u2026eparation for Remote File Systems."}
- {"dt": "2024-01-25T11:21:07.615022", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems."}
- {"dt": "2024-01-25T11:21:07.616066", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpcbind.target[0m - RPC Port Mapper."}
- {"dt": "2024-01-25T11:21:07.654271", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd.service\u2026us monitor for NFSv2/3 locking...."}
- {"dt": "2024-01-25T11:21:07.674661", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026 Record Runlevel Change in UTMP..."}
- {"dt": "2024-01-25T11:21:07.797626", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026 - Record Runlevel Change in UTMP."}
- {"dt": "2024-01-25T11:21:07.803453", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd.service\u2026atus monitor for NFSv2/3 locking.."}
- {"dt": "2024-01-25T11:21:07.909689", "lvl": "target", "msg": "You are in emergency mode. After logging in, type \\\"journalctl -xb\\\" to view"}
- {"dt": "2024-01-25T11:21:07.909889", "lvl": "target", "msg": "system logs, \\\"systemctl reboot\\\" to reboot, \\\"systemctl default\\\" or \\\"exit\\\""}
- {"dt": "2024-01-25T11:21:07.909993", "lvl": "target", "msg": "to boot into default mode."}
- {"dt": "2024-01-25T11:21:07.974866", "lvl": "target", "msg": ""}
- {"dt": "2024-01-25T11:21:07.975301", "lvl": "target", "msg": "Cannot open access to console, the root account is locked."}
- {"dt": "2024-01-25T11:21:07.975409", "lvl": "target", "msg": "See sulogin(8) man page for more details."}
- {"dt": "2024-01-25T11:21:07.975518", "lvl": "target", "msg": ""}
- {"dt": "2024-01-25T11:21:07.975620", "lvl": "target", "msg": "Press Enter to continue."}
- {"dt": "2024-01-25T11:24:48.207101", "lvl": "exception", "msg": "The job was canceled"}
- {"dt": "2024-01-25T11:24:48.207292", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:05:17) [common]"}
- {"dt": "2024-01-25T11:24:48.207384", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "316.73", "extra": {"fail": "The job was canceled"}, "level": "2.2.1", "namespace": "common", "result": "fail"}}
- {"dt": "2024-01-25T11:24:48.207826", "lvl": "exception", "msg": "The job was canceled"}
- {"dt": "2024-01-25T11:24:48.207943", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:05:17) [common]"}
- {"dt": "2024-01-25T11:24:48.208069", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "316.99", "extra": {"fail": "The job was canceled"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2024-01-25T11:24:48.208261", "lvl": "exception", "msg": "The job was canceled"}
- {"dt": "2024-01-25T11:24:48.208366", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:05:17) [common]"}
- {"dt": "2024-01-25T11:24:48.208471", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "317.00", "extra": {"fail": "The job was canceled"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2024-01-25T11:24:48.208690", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-01-25T11:24:48.208868", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/kernel"}
- {"dt": "2024-01-25T11:24:48.223786", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs"}
- {"dt": "2024-01-25T11:24:48.224041", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.modules"}
- {"dt": "2024-01-25T11:24:48.240683", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1/deployimages-zi88fqe1/rootfs.overlay-00"}
- {"dt": "2024-01-25T11:24:48.254049", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-01-25T11:24:48.254124", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-01-25T11:24:48.254190", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-01-25T11:24:48.254348", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-01-25T11:24:49.255397", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-01-25T11:24:49.255617", "lvl": "input", "msg": "poweroff\n"}
- {"dt": "2024-01-25T11:24:49.355939", "lvl": "target", "msg": "poweroff"}
- {"dt": "2024-01-25T11:24:49.359004", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-01-25T11:24:49.559501", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-01-25T11:24:49.559716", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/tuxrun-6sxo46uj-1"}
- {"dt": "2024-01-25T11:24:49.561976", "lvl": "error", "msg": "JobCanceled: The job was canceled"}
- {"dt": "2024-01-25T11:24:49.562100", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "The job was canceled", "error_type": "Canceled", "result": "fail"}}
