- {"dt": "2024-07-11T07:41:16.444750", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-07-11T07:41:16.444855", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-07-11T07:41:16.444939", "lvl": "info", "msg": "Start time: 2024-07-11 07:41:16.444930+00:00 (UTC)"}
- {"dt": "2024-07-11T07:41:16.446039", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2j0OwtEQb1ULe2tRHKXZWcfs0Xr/Image.gz exists"}
- {"dt": "2024-07-11T07:41:16.828368", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-07-11T07:41:17.423539", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4.xz exists"}
- {"dt": "2024-07-11T07:41:17.423750", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2j0OwtEQb1ULe2tRHKXZWcfs0Xr/modules.tar.xz exists"}
- {"dt": "2024-07-11T07:41:17.544984", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-07-11T07:41:18.125005", "lvl": "debug", "msg": "Validating that https://storage.dev.tuxboot.com/overlays/debian/bookworm/arm64/20240216/kvm-unit-tests/master/kvm-unit-tests.tar.xz exists"}
- {"dt": "2024-07-11T07:41:18.573324", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-07-11T07:41:18.573577", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-07-11T07:41:18.573650", "lvl": "warning", "msg": "Missing image arg for rootfs.overlay-00"}
- {"dt": "2024-07-11T07:41:18.573803", "lvl": "info", "msg": "validate duration: 2.13"}
- {"dt": "2024-07-11T07:41:18.573864", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-07-11T07:41:18.573969", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.574032", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.574210", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w"}
- {"dt": "2024-07-11T07:41:18.574382", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin"}
- {"dt": "2024-07-11T07:41:18.574493", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/tests"}
- {"dt": "2024-07-11T07:41:18.574598", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/results"}
- {"dt": "2024-07-11T07:41:18.574679", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-add-keys"}
- {"dt": "2024-07-11T07:41:18.574830", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-add-sources"}
- {"dt": "2024-07-11T07:41:18.574967", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-07-11T07:41:18.575099", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-07-11T07:41:18.575230", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-common-functions"}
- {"dt": "2024-07-11T07:41:18.575355", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-07-11T07:41:18.575482", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-install-packages"}
- {"dt": "2024-07-11T07:41:18.575605", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-07-11T07:41:18.575726", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-os-build"}
- {"dt": "2024-07-11T07:41:18.575848", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-07-11T07:41:18.575970", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-07-11T07:41:18.576093", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-target-ip"}
- {"dt": "2024-07-11T07:41:18.576213", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-target-mac"}
- {"dt": "2024-07-11T07:41:18.576335", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-target-storage"}
- {"dt": "2024-07-11T07:41:18.576458", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-case"}
- {"dt": "2024-07-11T07:41:18.576584", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-event"}
- {"dt": "2024-07-11T07:41:18.576707", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-07-11T07:41:18.576827", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-raise"}
- {"dt": "2024-07-11T07:41:18.576947", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-reference"}
- {"dt": "2024-07-11T07:41:18.577067", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-runner"}
- {"dt": "2024-07-11T07:41:18.577188", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-set"}
- {"dt": "2024-07-11T07:41:18.577314", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-test-shell"}
- {"dt": "2024-07-11T07:41:18.577439", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-07-11T07:41:18.577600", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-07-11T07:41:18.577712", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/environment"}
- {"dt": "2024-07-11T07:41:18.577805", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-07-11T07:41:18.577857", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-07-11T07:41:18.577901", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=10.0.2.100"}
- {"dt": "2024-07-11T07:41:18.577981", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578029", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-07-11T07:41:18.578084", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578144", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578187", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-07-11T07:41:18.578237", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578310", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578366", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-07-11T07:41:18.578427", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578474", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-07-11T07:41:18.578780", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-07-11T07:41:18.578847", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.578905", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.579299", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.579354", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-overlay", "level": "1.1.3.2", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline"}}}
- {"dt": "2024-07-11T07:41:18.579464", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.579945", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.580002", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-install-overlay", "level": "1.1.3.3", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2024-07-11T07:41:18.580109", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.580576", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/0/tests/0_commands test_uuid 1_1.1.3.1"}
- {"dt": "2024-07-11T07:41:18.580803", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.580860", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/0/tests/0_commands/run.sh"}}}
- {"dt": "2024-07-11T07:41:18.580953", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-07-11T07:41:18.580994", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/lava-overlay-3_vtbs5w/lava-1/0 for stage 0"}
- {"dt": "2024-07-11T07:41:18.581071", "lvl": "debug", "msg": "- 0_commands"}
- {"dt": "2024-07-11T07:41:18.581158", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.581220", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.586785", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.586870", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.586933", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.586993", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587051", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587097", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-07-11T07:41:18.587148", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587204", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587257", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587314", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587371", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:18.587511", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2j0OwtEQb1ULe2tRHKXZWcfs0Xr/Image.gz"}
- {"dt": "2024-07-11T07:41:18.587566", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/kernel/Image"}
- {"dt": "2024-07-11T07:41:18.587609", "lvl": "debug", "msg": "total size: 16403832 (15\u202FMB)"}
- {"dt": "2024-07-11T07:41:18.587651", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-07-11T07:41:19.205400", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.599515", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.701841", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.770468", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.840440", "lvl": "debug", "msg": "progress  20\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.910278", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:41:19.980192", "lvl": "debug", "msg": "progress  30\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.050136", "lvl": "debug", "msg": "progress  35\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.120251", "lvl": "debug", "msg": "progress  40\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.190576", "lvl": "debug", "msg": "progress  45\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.260110", "lvl": "debug", "msg": "progress  50\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.330268", "lvl": "debug", "msg": "progress  55\u202F% (8\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.400168", "lvl": "debug", "msg": "progress  60\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.472148", "lvl": "debug", "msg": "progress  65\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.539712", "lvl": "debug", "msg": "progress  70\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.609876", "lvl": "debug", "msg": "progress  75\u202F% (11\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.680080", "lvl": "debug", "msg": "progress  80\u202F% (12\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.893413", "lvl": "debug", "msg": "progress  85\u202F% (13\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.939643", "lvl": "debug", "msg": "progress  90\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:41:20.970688", "lvl": "debug", "msg": "progress  95\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:41:21.004148", "lvl": "debug", "msg": "progress 100\u202F% (15\u202FMB)"}
- {"dt": "2024-07-11T07:41:21.010877", "lvl": "info", "msg": "15\u202FMB downloaded in 2.42\u202Fs (6.46\u202FMB/s)"}
- {"dt": "2024-07-11T07:41:21.011148", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-07-11T07:41:21.011239", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.4.1", "duration": "2.42", "result": "pass", "extra": {"label": "kernel", "size": ! "16403832", "md5sum": "70236e56b07cf9e64088eac7070c9f21", "sha256sum": "97b8bb96eb209f7c2f842e8a4f054bbf8dda852bf879aa6270f5449c0e655eab", "sha512sum": "d0cf350b7f1bcb493f15ce834ceb11fb1a73695687b1076613c32b43cef62104dafb5d64d595f9f327e8a732b0518b8a3c12b91343ba61b24390106ecd1f19b6"}}}
- {"dt": "2024-07-11T07:41:21.011445", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-07-11T07:41:21.011546", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:58) [common]"}
- {"dt": "2024-07-11T07:41:21.011649", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:58) [common]"}
- {"dt": "2024-07-11T07:41:21.011890", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4.xz"}
- {"dt": "2024-07-11T07:41:21.011970", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4"}
- {"dt": "2024-07-11T07:41:21.012040", "lvl": "debug", "msg": "total size: 192741048 (183\u202FMB)"}
- {"dt": "2024-07-11T07:41:21.012114", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:41:21.012638", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:21.384304", "lvl": "debug", "msg": "progress   5\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:41:21.703121", "lvl": "debug", "msg": "progress  10\u202F% (18\u202FMB)"}
- {"dt": "2024-07-11T07:41:22.024285", "lvl": "debug", "msg": "progress  15\u202F% (27\u202FMB)"}
- {"dt": "2024-07-11T07:41:22.348152", "lvl": "debug", "msg": "progress  20\u202F% (36\u202FMB)"}
- {"dt": "2024-07-11T07:41:22.679330", "lvl": "debug", "msg": "progress  25\u202F% (45\u202FMB)"}
- {"dt": "2024-07-11T07:41:22.995239", "lvl": "debug", "msg": "progress  30\u202F% (55\u202FMB)"}
- {"dt": "2024-07-11T07:41:23.315867", "lvl": "debug", "msg": "progress  35\u202F% (64\u202FMB)"}
- {"dt": "2024-07-11T07:41:23.636543", "lvl": "debug", "msg": "progress  40\u202F% (73\u202FMB)"}
- {"dt": "2024-07-11T07:41:23.950467", "lvl": "debug", "msg": "progress  45\u202F% (82\u202FMB)"}
- {"dt": "2024-07-11T07:41:24.274233", "lvl": "debug", "msg": "progress  50\u202F% (91\u202FMB)"}
- {"dt": "2024-07-11T07:41:24.602468", "lvl": "debug", "msg": "progress  55\u202F% (101\u202FMB)"}
- {"dt": "2024-07-11T07:41:24.917648", "lvl": "debug", "msg": "progress  60\u202F% (110\u202FMB)"}
- {"dt": "2024-07-11T07:41:25.258846", "lvl": "debug", "msg": "progress  65\u202F% (119\u202FMB)"}
- {"dt": "2024-07-11T07:41:25.586450", "lvl": "debug", "msg": "progress  70\u202F% (128\u202FMB)"}
- {"dt": "2024-07-11T07:41:25.886532", "lvl": "debug", "msg": "progress  75\u202F% (137\u202FMB)"}
- {"dt": "2024-07-11T07:41:26.207759", "lvl": "debug", "msg": "progress  80\u202F% (147\u202FMB)"}
- {"dt": "2024-07-11T07:41:26.518160", "lvl": "debug", "msg": "progress  85\u202F% (156\u202FMB)"}
- {"dt": "2024-07-11T07:41:26.824565", "lvl": "debug", "msg": "progress  90\u202F% (165\u202FMB)"}
- {"dt": "2024-07-11T07:41:27.087700", "lvl": "debug", "msg": "progress  95\u202F% (174\u202FMB)"}
- {"dt": "2024-07-11T07:41:28.725950", "lvl": "debug", "msg": "progress 100\u202F% (183\u202FMB)"}
- {"dt": "2024-07-11T07:41:29.903453", "lvl": "info", "msg": "183\u202FMB downloaded in 8.89\u202Fs (20.67\u202FMB/s)"}
- {"dt": "2024-07-11T07:41:29.903711", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:09) [common]"}
- {"dt": "2024-07-11T07:41:29.903784", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "8.89", "result": "pass", "extra": {"label": "rootfs", "size": ! "192741048", "md5sum": "6eee656eff611edb504603c92d50df3e", "sha256sum": "f3592bf199e57df8c9160949e830ea3415acbf526acf5579cf5c75ff89618917", "sha512sum": "c75faf3e3c5f36b15dca12e1ff7a8fbe4ed2f9234bcb73f5f8b7724d970b8c33fb6e06b08dedfc9f0c1264e9f4415c94c7e5e785ac7154b40a8c21a719d641b3"}}}
- {"dt": "2024-07-11T07:41:29.903937", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:49) [common]"}
- {"dt": "2024-07-11T07:41:29.904014", "lvl": "debug", "msg": "start: 1.5.2.1 http-download (timeout 00:04:49) [common]"}
- {"dt": "2024-07-11T07:41:29.904231", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2j0OwtEQb1ULe2tRHKXZWcfs0Xr/modules.tar.xz"}
- {"dt": "2024-07-11T07:41:29.904319", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.modules/modules.tar"}
- {"dt": "2024-07-11T07:41:29.904377", "lvl": "debug", "msg": "total size: 18399448 (17\u202FMB)"}
- {"dt": "2024-07-11T07:41:29.904435", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:41:30.589401", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.020938", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.134584", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.213872", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.292489", "lvl": "debug", "msg": "progress  20\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.372063", "lvl": "debug", "msg": "progress  25\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.450710", "lvl": "debug", "msg": "progress  30\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.529350", "lvl": "debug", "msg": "progress  35\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.608333", "lvl": "debug", "msg": "progress  40\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.687524", "lvl": "debug", "msg": "progress  45\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.766391", "lvl": "debug", "msg": "progress  50\u202F% (8\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.846482", "lvl": "debug", "msg": "progress  55\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:41:31.925159", "lvl": "debug", "msg": "progress  60\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.003949", "lvl": "debug", "msg": "progress  65\u202F% (11\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.085864", "lvl": "debug", "msg": "progress  70\u202F% (12\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.362186", "lvl": "debug", "msg": "progress  75\u202F% (13\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.407095", "lvl": "debug", "msg": "progress  80\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.452868", "lvl": "debug", "msg": "progress  85\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.542128", "lvl": "debug", "msg": "progress  90\u202F% (15\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.688791", "lvl": "debug", "msg": "progress  95\u202F% (16\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.738775", "lvl": "debug", "msg": "progress 100\u202F% (17\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.741661", "lvl": "info", "msg": "17\u202FMB downloaded in 2.84\u202Fs (6.18\u202FMB/s)"}
- {"dt": "2024-07-11T07:41:32.741855", "lvl": "debug", "msg": "end: 1.5.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:41:32.741950", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.2.1", "duration": "2.84", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "18399448", "md5sum": "f236d5688543cd86158a92882255b2b3", "sha256sum": "7d0adaaf59f7e18446b80cce1e6b0f61b58991e02dc4bb7381426ee057531b41", "sha512sum": "e83470e9d92670ee3d0e137226517f78596acf0681174908c6eaaac9f55ab9584b17afdfb9ee13d0377413d88d3d09d2bdcaab869427d751cb74235943b8f4a8"}}}
- {"dt": "2024-07-11T07:41:32.742145", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:41:32.742224", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:04:46) [common]"}
- {"dt": "2024-07-11T07:41:32.742334", "lvl": "debug", "msg": "start: 1.5.3.1 http-download (timeout 00:04:46) [common]"}
- {"dt": "2024-07-11T07:41:32.742568", "lvl": "info", "msg": "downloading https://storage.dev.tuxboot.com/overlays/debian/bookworm/arm64/20240216/kvm-unit-tests/master/kvm-unit-tests.tar.xz"}
- {"dt": "2024-07-11T07:41:32.742641", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.overlay-00/kvm-unit-tests.tar"}
- {"dt": "2024-07-11T07:41:32.742701", "lvl": "debug", "msg": "total size: 1364352 (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:32.742789", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:41:33.492842", "lvl": "debug", "msg": "progress   2\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.598236", "lvl": "debug", "msg": "progress   7\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.603900", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.707648", "lvl": "debug", "msg": "progress  19\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.713873", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.808575", "lvl": "debug", "msg": "progress  31\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.814788", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.822721", "lvl": "debug", "msg": "progress  43\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.828034", "lvl": "debug", "msg": "progress  48\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.837279", "lvl": "debug", "msg": "progress  55\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.912215", "lvl": "debug", "msg": "progress  60\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.920906", "lvl": "debug", "msg": "progress  67\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.926687", "lvl": "debug", "msg": "progress  72\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.934803", "lvl": "debug", "msg": "progress  79\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.940477", "lvl": "debug", "msg": "progress  84\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.949180", "lvl": "debug", "msg": "progress  91\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.954659", "lvl": "debug", "msg": "progress  96\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:41:33.959837", "lvl": "info", "msg": "1\u202FMB downloaded in 1.22\u202Fs (1.07\u202FMB/s)"}
- {"dt": "2024-07-11T07:41:33.960021", "lvl": "debug", "msg": "end: 1.5.3.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:33.960085", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.3.1", "duration": "1.22", "result": "pass", "extra": {"label": "rootfs.overlay-00", "size": ! "1364352", "md5sum": "0998f24ccef636eccd7561bbe058c5a8", "sha256sum": "74b923dc8c71d86e7da540929c04ae135b922df79251dc513072d0762a9592f3", "sha512sum": "e6f7c545d9ac0a5c57df9c60233442af001bea79eb28dcac07fafaf9cde0885489514924454e0306a3cb17b1a02d33481bd29bbc8f08d9804131d7607b415d63"}}}
- {"dt": "2024-07-11T07:41:33.960211", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:33.960282", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:04:45) [common]"}
- {"dt": "2024-07-11T07:41:33.960340", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4'"}
- {"dt": "2024-07-11T07:41:35.835389", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-07-11T07:41:35.836105", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/compress-overlay-oyuvf9ik/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-07-11T07:41:35.843318", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.modules/modules.tar' to '/'"}
- {"dt": "2024-07-11T07:41:36.144654", "lvl": "debug", "msg": "- rootfs.overlay-00: '/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.overlay-00/kvm-unit-tests.tar' to '/'"}
- {"dt": "2024-07-11T07:41:36.518457", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:41:36.518819", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:16) [common]"}
- {"dt": "2024-07-11T07:41:36.518914", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:18) [common]"}
- {"dt": "2024-07-11T07:41:36.519005", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:36.519077", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:36.519139", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:36.519403", "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-1n8tn7lz-1/deployimages-6jy1hgj1/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw kvm-arm.mode=nvhe earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4,if=none,format=raw,id=hd0 -device virtio-blk-device,drive=hd0"}
- {"dt": "2024-07-11T07:41:36.522760", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-07-11T07:41:36.523127", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:36.523197", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "1:8.2.4+ds-1", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2024-07-11T07:41:36.523327", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:36.523398", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:36.523472", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-07-11T07:41:36.523525", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:05:00)"}
- {"dt": "2024-07-11T07:41:36.666344", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-07-11T07:41:36.666875", "lvl": "target", "msg": "[    0.000000] Linux version 6.10.0-rc7-next-20240709 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP @1720521086"}
- {"dt": "2024-07-11T07:41:36.666980", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-07-11T07:41:36.667074", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-07-11T07:41:36.667226", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-07-11T07:41:36.667340", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-07-11T07:41:36.667567", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-07-11T07:41:36.667737", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-07-11T07:41:36.668064", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:36.668176", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-07-11T07:41:36.668291", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-07-11T07:41:36.668407", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-07-11T07:41:36.668524", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-07-11T07:41:36.668602", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-07-11T07:41:36.668675", "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-07-11T07:41:36.668819", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:05:00)"}
- {"dt": "2024-07-11T07:41:36.668897", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:02:30)"}
- {"dt": "2024-07-11T07:41:36.671651", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-07-11T07:41:36.672031", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:36.672573", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f5fb240-0x13f5fdfff]"}
- {"dt": "2024-07-11T07:41:36.674561", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-07-11T07:41:36.675027", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-07-11T07:41:36.675148", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-07-11T07:41:36.675334", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:36.675475", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-07-11T07:41:36.675619", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-07-11T07:41:36.675861", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:36.676277", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:36.700026", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fc000000 on node -1"}
- {"dt": "2024-07-11T07:41:36.700905", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-07-11T07:41:36.701204", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-07-11T07:41:36.701389", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-07-11T07:41:36.701675", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-07-11T07:41:36.701872", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-07-11T07:41:36.704243", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 34 pages/cpu s100440 r8192 d30632 u139264"}
- {"dt": "2024-07-11T07:41:36.704998", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s100440 r8192 d30632 u139264 alloc=34*4096"}
- {"dt": "2024-07-11T07:41:36.705296", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-07-11T07:41:36.706690", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-07-11T07:41:36.707215", "lvl": "target", "msg": "[    0.000000] CPU features: SYS_ID_AA64MMFR1_EL1[11:8]: forced to 0"}
- {"dt": "2024-07-11T07:41:36.708935", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:41:36.709321", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-07-11T07:41:36.709591", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-07-11T07:41:36.709875", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-07-11T07:41:36.710081", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-07-11T07:41:36.710346", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-07-11T07:41:36.713630", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-07-11T07:41:36.715451", "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 kvm-arm.mode=nvhe earlycon"}
- {"dt": "2024-07-11T07:41:36.718074", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-07-11T07:41:36.722911", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-07-11T07:41:36.724662", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-07-11T07:41:36.726321", "lvl": "target", "msg": "<6>[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-07-11T07:41:36.726496", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-07-11T07:41:36.726641", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2024-07-11T07:41:36.728008", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off"}
- {"dt": "2024-07-11T07:41:36.728342", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-07-11T07:41:36.803145", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000f8000000-0x00000000fc000000] (64MB)"}
- {"dt": "2024-07-11T07:41:36.827680", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-07-11T07:41:36.829338", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocating 65265 entries in 255 pages"}
- {"dt": "2024-07-11T07:41:37.222308", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocated 255 pages with 8 groups"}
- {"dt": "2024-07-11T07:41:37.225029", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-07-11T07:41:37.231470", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-07-11T07:41:37.231691", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2024-07-11T07:41:37.232015", "lvl": "target", "msg": "<6>[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:41:37.232208", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:41:37.232625", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-07-11T07:41:37.232931", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-07-11T07:41:37.234479", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-07-11T07:41:37.234812", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-07-11T07:41:37.254110", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-07-11T07:41:37.260097", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-07-11T07:41:37.260284", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-07-11T07:41:37.260489", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-07-11T07:41:37.261948", "lvl": "target", "msg": "<6>[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-07-11T07:41:37.262282", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-07-11T07:41:37.262531", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-07-11T07:41:37.263234", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-07-11T07:41:37.268377", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-07-11T07:41:37.269410", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100210000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:41:37.269964", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100220000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:41:37.270902", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000100230000"}
- {"dt": "2024-07-11T07:41:37.271756", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100240000"}
- {"dt": "2024-07-11T07:41:37.273912", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-07-11T07:41:37.280406", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-07-11T07:41:37.281069", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-07-11T07:41:37.281740", "lvl": "target", "msg": "<6>[    0.000058] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-07-11T07:41:37.285052", "lvl": "target", "msg": "<6>[    0.003197] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)"}
- {"dt": "2024-07-11T07:41:37.298766", "lvl": "target", "msg": "<6>[    0.015064] Console: colour dummy device 80x25"}
- {"dt": "2024-07-11T07:41:37.305474", "lvl": "target", "msg": "<6>[    0.023303] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-07-11T07:41:37.305905", "lvl": "target", "msg": "<6>[    0.024219] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-07-11T07:41:37.310108", "lvl": "target", "msg": "<6>[    0.028186] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,tomoyo,bpf,ima,evm"}
- {"dt": "2024-07-11T07:41:37.311241", "lvl": "target", "msg": "<6>[    0.029628] landlock: Up and running."}
- {"dt": "2024-07-11T07:41:37.311336", "lvl": "target", "msg": "<6>[    0.029790] Yama: becoming mindful."}
- {"dt": "2024-07-11T07:41:37.314662", "lvl": "target", "msg": "<6>[    0.033040] AppArmor: AppArmor initialized"}
- {"dt": "2024-07-11T07:41:37.314761", "lvl": "target", "msg": "<6>[    0.033264] TOMOYO Linux initialized"}
- {"dt": "2024-07-11T07:41:37.315534", "lvl": "target", "msg": "<6>[    0.033924] LSM support for eBPF active"}
- {"dt": "2024-07-11T07:41:37.319318", "lvl": "target", "msg": "<6>[    0.037675] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.319578", "lvl": "target", "msg": "<6>[    0.038003] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.353177", "lvl": "target", "msg": "<4>[    0.071456] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-07-11T07:41:37.367411", "lvl": "target", "msg": "<6>[    0.085711] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-07-11T07:41:37.367588", "lvl": "target", "msg": "<6>[    0.085948] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-07-11T07:41:37.378383", "lvl": "target", "msg": "<6>[    0.096745] Platform MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:37.379158", "lvl": "target", "msg": "<6>[    0.097404] PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:37.379541", "lvl": "target", "msg": "<6>[    0.098044] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:37.382869", "lvl": "target", "msg": "<6>[    0.101261] EFI services will not be available."}
- {"dt": "2024-07-11T07:41:37.385168", "lvl": "target", "msg": "<6>[    0.103553] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-07-11T07:41:37.406231", "lvl": "target", "msg": "<6>[    0.111235] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-07-11T07:41:37.406414", "lvl": "target", "msg": "<6>[    0.114284] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-07-11T07:41:37.406695", "lvl": "target", "msg": "<6>[    0.115588] GICv3: CPU1: using allocated LPI pending table @0x0000000100250000"}
- {"dt": "2024-07-11T07:41:37.407150", "lvl": "target", "msg": "<6>[    0.118590] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-07-11T07:41:37.407231", "lvl": "target", "msg": "<6>[    0.124532] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-07-11T07:41:37.407380", "lvl": "target", "msg": "<6>[    0.125866] SMP: Total of 2 processors activated."}
- {"dt": "2024-07-11T07:41:37.407556", "lvl": "target", "msg": "<6>[    0.126086] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-07-11T07:41:37.407967", "lvl": "target", "msg": "<6>[    0.126453] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-07-11T07:41:37.408172", "lvl": "target", "msg": "<6>[    0.126683] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-07-11T07:41:37.408355", "lvl": "target", "msg": "<6>[    0.126862] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-07-11T07:41:37.408553", "lvl": "target", "msg": "<6>[    0.127034] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-07-11T07:41:37.408823", "lvl": "target", "msg": "<6>[    0.127253] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-07-11T07:41:37.409129", "lvl": "target", "msg": "<6>[    0.127551] CPU features: detected: Common not Private translations"}
- {"dt": "2024-07-11T07:41:37.409260", "lvl": "target", "msg": "<6>[    0.127751] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-07-11T07:41:37.409562", "lvl": "target", "msg": "<6>[    0.128007] CPU features: detected: Data cache clean to Point of Deep Persistence"}
- {"dt": "2024-07-11T07:41:37.409771", "lvl": "target", "msg": "<6>[    0.128240] CPU features: detected: Data cache clean to Point of Persistence"}
- {"dt": "2024-07-11T07:41:37.410009", "lvl": "target", "msg": "<6>[    0.128452] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-07-11T07:41:37.410187", "lvl": "target", "msg": "<6>[    0.128692] CPU features: detected: E0PD"}
- {"dt": "2024-07-11T07:41:37.410457", "lvl": "target", "msg": "<6>[    0.128868] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-07-11T07:41:37.410721", "lvl": "target", "msg": "<6>[    0.129140] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-07-11T07:41:37.410955", "lvl": "target", "msg": "<6>[    0.129406] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-07-11T07:41:37.411407", "lvl": "target", "msg": "<6>[    0.129713] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:41:37.411770", "lvl": "target", "msg": "<6>[    0.130177] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-07-11T07:41:37.412097", "lvl": "target", "msg": "<6>[    0.130486] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-07-11T07:41:37.412323", "lvl": "target", "msg": "<6>[    0.130780] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-07-11T07:41:37.412617", "lvl": "target", "msg": "<6>[    0.131006] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2024-07-11T07:41:37.412839", "lvl": "target", "msg": "<6>[    0.131297] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-07-11T07:41:37.413026", "lvl": "target", "msg": "<6>[    0.131524] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-07-11T07:41:37.413219", "lvl": "target", "msg": "<6>[    0.131708] CPU features: detected: Random Number Generator"}
- {"dt": "2024-07-11T07:41:37.413538", "lvl": "target", "msg": "<6>[    0.131900] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-07-11T07:41:37.413756", "lvl": "target", "msg": "<6>[    0.132192] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-07-11T07:41:37.414033", "lvl": "target", "msg": "<6>[    0.132438] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2024-07-11T07:41:37.414236", "lvl": "target", "msg": "<6>[    0.132713] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-07-11T07:41:37.414478", "lvl": "target", "msg": "<6>[    0.132960] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-07-11T07:41:37.414611", "lvl": "target", "msg": "<6>[    0.133158] CPU features: detected: FA64"}
- {"dt": "2024-07-11T07:41:37.414827", "lvl": "target", "msg": "<6>[    0.133292] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-07-11T07:41:37.415009", "lvl": "target", "msg": "<6>[    0.133506] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-07-11T07:41:37.428231", "lvl": "target", "msg": "<6>[    0.144455] alternatives: applying system-wide alternatives"}
- {"dt": "2024-07-11T07:41:37.444467", "lvl": "target", "msg": "<6>[    0.162328] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-07-11T07:41:37.445145", "lvl": "target", "msg": "<6>[    0.163327] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:41:37.445228", "lvl": "target", "msg": "<6>[    0.163645] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-07-11T07:41:37.446836", "lvl": "target", "msg": "<6>[    0.165222] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-07-11T07:41:37.447123", "lvl": "target", "msg": "<6>[    0.165529] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:41:37.447470", "lvl": "target", "msg": "<6>[    0.165819] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-07-11T07:41:37.479778", "lvl": "target", "msg": "<6>[    0.198035] node 0 deferred pages initialised in 8ms"}
- {"dt": "2024-07-11T07:41:37.481146", "lvl": "target", "msg": "<6>[    0.199282] Memory: 3920176K/4194304K available (20096K kernel code, 6226K rwdata, 12872K rodata, 14016K init, 981K bss, 204312K reserved, 65536K cma-reserved)"}
- {"dt": "2024-07-11T07:41:37.498383", "lvl": "target", "msg": "<6>[    0.216698] devtmpfs: initialized"}
- {"dt": "2024-07-11T07:41:37.529175", "lvl": "target", "msg": "<6>[    0.247164] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-07-11T07:41:37.530174", "lvl": "target", "msg": "<6>[    0.248541] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.532879", "lvl": "target", "msg": "<6>[    0.251260] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-07-11T07:41:37.533253", "lvl": "target", "msg": "<6>[    0.251600] 0 pages in range for non-PLT usage"}
- {"dt": "2024-07-11T07:41:37.537490", "lvl": "target", "msg": "<6>[    0.251681] 510672 pages in range for PLT usage"}
- {"dt": "2024-07-11T07:41:37.537694", "lvl": "target", "msg": "<6>[    0.255751] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-07-11T07:41:37.547529", "lvl": "target", "msg": "<6>[    0.265901] DMI not present or invalid."}
- {"dt": "2024-07-11T07:41:37.564869", "lvl": "target", "msg": "<6>[    0.283096] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-07-11T07:41:37.578581", "lvl": "target", "msg": "<6>[    0.296656] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:37.580474", "lvl": "target", "msg": "<6>[    0.298798] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:37.583057", "lvl": "target", "msg": "<6>[    0.301376] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:37.583706", "lvl": "target", "msg": "<6>[    0.302099] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-07-11T07:41:37.586683", "lvl": "target", "msg": "<5>[    0.304926] audit: type=2000 audit(0.272:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-07-11T07:41:37.598664", "lvl": "target", "msg": "<6>[    0.316841] thermal_sys: Registered thermal governor 'fair_share'"}
- {"dt": "2024-07-11T07:41:37.599189", "lvl": "target", "msg": "<6>[    0.316966] thermal_sys: Registered thermal governor 'bang_bang'"}
- {"dt": "2024-07-11T07:41:37.599444", "lvl": "target", "msg": "<6>[    0.317330] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-07-11T07:41:37.599765", "lvl": "target", "msg": "<6>[    0.317806] thermal_sys: Registered thermal governor 'user_space'"}
- {"dt": "2024-07-11T07:41:37.601531", "lvl": "target", "msg": "<6>[    0.318136] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-07-11T07:41:37.601725", "lvl": "target", "msg": "<6>[    0.319876] cpuidle: using governor ladder"}
- {"dt": "2024-07-11T07:41:37.602540", "lvl": "target", "msg": "<6>[    0.320924] cpuidle: using governor menu"}
- {"dt": "2024-07-11T07:41:37.606209", "lvl": "target", "msg": "<6>[    0.324548] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-07-11T07:41:37.607665", "lvl": "target", "msg": "<6>[    0.325779] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-07-11T07:41:37.627660", "lvl": "target", "msg": "<6>[    0.345989] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-07-11T07:41:37.779031", "lvl": "target", "msg": "<6>[    0.497282] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-07-11T07:41:37.780823", "lvl": "target", "msg": "<6>[    0.499105] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:41:37.780993", "lvl": "target", "msg": "<6>[    0.499105] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:41:37.781281", "lvl": "target", "msg": "<6>[    0.499746] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:41:37.781490", "lvl": "target", "msg": "<6>[    0.499746] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:41:37.805732", "lvl": "target", "msg": "<6>[    0.524038] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:37.805966", "lvl": "target", "msg": "<6>[    0.524385] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-07-11T07:41:37.806298", "lvl": "target", "msg": "<6>[    0.524677] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:37.806560", "lvl": "target", "msg": "<6>[    0.524971] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-07-11T07:41:37.806844", "lvl": "target", "msg": "<6>[    0.525247] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:37.807210", "lvl": "target", "msg": "<6>[    0.525530] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-07-11T07:41:37.807734", "lvl": "target", "msg": "<6>[    0.526116] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:37.807983", "lvl": "target", "msg": "<6>[    0.526417] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-07-11T07:41:37.819509", "lvl": "target", "msg": "<6>[    0.537876] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-07-11T07:41:37.828277", "lvl": "target", "msg": "<6>[    0.546626] ACPI: Interpreter disabled."}
- {"dt": "2024-07-11T07:41:37.844686", "lvl": "target", "msg": "<6>[    0.563022] iommu: Default domain type: Translated"}
- {"dt": "2024-07-11T07:41:37.844901", "lvl": "target", "msg": "<6>[    0.563309] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-07-11T07:41:37.848943", "lvl": "target", "msg": "<5>[    0.567293] SCSI subsystem initialized"}
- {"dt": "2024-07-11T07:41:37.853998", "lvl": "target", "msg": "<6>[    0.572339] mc: Linux media interface: v0.10"}
- {"dt": "2024-07-11T07:41:37.854461", "lvl": "target", "msg": "<6>[    0.572865] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-07-11T07:41:37.854978", "lvl": "target", "msg": "<6>[    0.573421] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-07-11T07:41:37.855367", "lvl": "target", "msg": "<6>[    0.573667] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-07-11T07:41:37.855738", "lvl": "target", "msg": "<6>[    0.574192] PTP clock support registered"}
- {"dt": "2024-07-11T07:41:37.857139", "lvl": "target", "msg": "<6>[    0.575506] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-07-11T07:41:37.859248", "lvl": "target", "msg": "<6>[    0.577606] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-07-11T07:41:37.865152", "lvl": "target", "msg": "<6>[    0.583491] FPGA manager framework"}
- {"dt": "2024-07-11T07:41:37.865933", "lvl": "target", "msg": "<6>[    0.584289] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-07-11T07:41:37.875640", "lvl": "target", "msg": "<6>[    0.593931] NetLabel: Initializing"}
- {"dt": "2024-07-11T07:41:37.875824", "lvl": "target", "msg": "<6>[    0.594157] NetLabel:  domain hash size = 128"}
- {"dt": "2024-07-11T07:41:37.875938", "lvl": "target", "msg": "<6>[    0.594378] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-07-11T07:41:37.877317", "lvl": "target", "msg": "<6>[    0.595675] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-07-11T07:41:37.879470", "lvl": "target", "msg": "<6>[    0.597804] vgaarb: loaded"}
- {"dt": "2024-07-11T07:41:37.883927", "lvl": "target", "msg": "<6>[    0.602251] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-07-11T07:41:37.889858", "lvl": "target", "msg": "<5>[    0.608161] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-07-11T07:41:37.890218", "lvl": "target", "msg": "<6>[    0.608570] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-07-11T07:41:37.893844", "lvl": "target", "msg": "<5>[    0.612204] netfs: FS-Cache loaded"}
- {"dt": "2024-07-11T07:41:37.898842", "lvl": "target", "msg": "<6>[    0.617184] AppArmor: AppArmor Filesystem Enabled"}
- {"dt": "2024-07-11T07:41:37.899308", "lvl": "target", "msg": "<6>[    0.617800] pnp: PnP ACPI: disabled"}
- {"dt": "2024-07-11T07:41:37.938376", "lvl": "target", "msg": "<6>[    0.656685] NET: Registered PF_INET protocol family"}
- {"dt": "2024-07-11T07:41:37.940378", "lvl": "target", "msg": "<6>[    0.658727] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.950672", "lvl": "target", "msg": "<6>[    0.668965] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.951266", "lvl": "target", "msg": "<6>[    0.669660] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.951916", "lvl": "target", "msg": "<6>[    0.670268] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.953274", "lvl": "target", "msg": "<6>[    0.671624] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.954068", "lvl": "target", "msg": "<6>[    0.672408] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-07-11T07:41:37.956296", "lvl": "target", "msg": "<6>[    0.674667] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.957092", "lvl": "target", "msg": "<6>[    0.675490] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.957679", "lvl": "target", "msg": "<6>[    0.676096] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:37.959301", "lvl": "target", "msg": "<6>[    0.677725] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-07-11T07:41:37.960046", "lvl": "target", "msg": "<6>[    0.678471] NET: Registered PF_XDP protocol family"}
- {"dt": "2024-07-11T07:41:37.960580", "lvl": "target", "msg": "<6>[    0.678878] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-07-11T07:41:37.973582", "lvl": "target", "msg": "<6>[    0.691922] kvm [1]: nv: 529 coarse grained trap handlers"}
- {"dt": "2024-07-11T07:41:37.974783", "lvl": "target", "msg": "<6>[    0.693146] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2024-07-11T07:41:37.976013", "lvl": "target", "msg": "<6>[    0.694348] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-07-11T07:41:37.985615", "lvl": "target", "msg": "<6>[    0.703918] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-07-11T07:41:37.985772", "lvl": "target", "msg": "<6>[    0.704283] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-07-11T07:41:37.986133", "lvl": "target", "msg": "<6>[    0.704561] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-07-11T07:41:37.987222", "lvl": "target", "msg": "<6>[    0.705596] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-07-11T07:41:37.988064", "lvl": "target", "msg": "<6>[    0.706391] kvm [1]: Hyp nVHE mode initialized successfully"}
- {"dt": "2024-07-11T07:41:37.994528", "lvl": "target", "msg": "<5>[    0.712860] Initialise system trusted keyrings"}
- {"dt": "2024-07-11T07:41:37.996252", "lvl": "target", "msg": "<5>[    0.714611] Key type blacklist registered"}
- {"dt": "2024-07-11T07:41:37.997685", "lvl": "target", "msg": "<6>[    0.715952] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-07-11T07:41:38.006802", "lvl": "target", "msg": "<6>[    0.725193] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-07-11T07:41:38.009460", "lvl": "target", "msg": "<5>[    0.727798] integrity: Platform Keyring initialized"}
- {"dt": "2024-07-11T07:41:38.009842", "lvl": "target", "msg": "<5>[    0.728283] integrity: Machine keyring initialized"}
- {"dt": "2024-07-11T07:41:38.079902", "lvl": "target", "msg": "<6>[    0.798144] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-07-11T07:41:38.080149", "lvl": "target", "msg": "<5>[    0.798561] Key type asymmetric registered"}
- {"dt": "2024-07-11T07:41:38.080303", "lvl": "target", "msg": "<5>[    0.798817] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-07-11T07:41:38.081081", "lvl": "target", "msg": "<6>[    0.799439] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)"}
- {"dt": "2024-07-11T07:41:38.082762", "lvl": "target", "msg": "<6>[    0.801099] io scheduler mq-deadline registered"}
- {"dt": "2024-07-11T07:41:38.144195", "lvl": "target", "msg": "<6>[    0.862421] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-07-11T07:41:38.157759", "lvl": "target", "msg": "<6>[    0.876031] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-07-11T07:41:38.158723", "lvl": "target", "msg": "<6>[    0.876981] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-07-11T07:41:38.159607", "lvl": "target", "msg": "<6>[    0.877805] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-07-11T07:41:38.160330", "lvl": "target", "msg": "<6>[    0.878483] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-07-11T07:41:38.161131", "lvl": "target", "msg": "<4>[    0.879465] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-07-11T07:41:38.162144", "lvl": "target", "msg": "<6>[    0.880359] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-07-11T07:41:38.163821", "lvl": "target", "msg": "<6>[    0.882240] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-07-11T07:41:38.164261", "lvl": "target", "msg": "<6>[    0.882721] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-07-11T07:41:38.164545", "lvl": "target", "msg": "<6>[    0.883006] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:41:38.164821", "lvl": "target", "msg": "<6>[    0.883235] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:41:38.165065", "lvl": "target", "msg": "<6>[    0.883507] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:41:38.166625", "lvl": "target", "msg": "<6>[    0.885038] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-07-11T07:41:38.172406", "lvl": "target", "msg": "<6>[    0.890740] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:41:38.172538", "lvl": "target", "msg": "<6>[    0.890981] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:41:38.172701", "lvl": "target", "msg": "<6>[    0.891179] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:41:38.372112", "lvl": "target", "msg": "<6>[    1.090396] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-07-11T07:41:38.387595", "lvl": "target", "msg": "<6>[    1.105906] Serial: AMBA driver"}
- {"dt": "2024-07-11T07:41:38.391274", "lvl": "target", "msg": "<6>[    1.109652] msm_serial: driver initialized"}
- {"dt": "2024-07-11T07:41:38.393399", "lvl": "target", "msg": "<6>[    1.111804] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-07-11T07:41:38.394446", "lvl": "target", "msg": "<6>[    1.112854] STM32 USART driver initialized"}
- {"dt": "2024-07-11T07:41:38.408976", "lvl": "target", "msg": "<6>[    1.127321] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-07-11T07:41:38.417039", "lvl": "target", "msg": "<5>[    1.135339] virtio_blk virtio0: [vda] 6059648 512-byte logical blocks (3.10 GB/2.89 GiB)"}
- {"dt": "2024-07-11T07:41:38.437939", "lvl": "target", "msg": "<6>[    1.156268] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-07-11T07:41:38.449135", "lvl": "target", "msg": "<5>[    1.167119] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-07-11T07:41:38.452269", "lvl": "target", "msg": "<6>[    1.170285] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:41:38.453378", "lvl": "target", "msg": "<6>[    1.171636] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:41:38.454971", "lvl": "target", "msg": "<6>[    1.173266] Using buffer write method"}
- {"dt": "2024-07-11T07:41:38.456114", "lvl": "target", "msg": "<7>[    1.174375] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:41:38.457047", "lvl": "target", "msg": "<5>[    1.175173] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-07-11T07:41:38.458589", "lvl": "target", "msg": "<6>[    1.176595] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:41:38.459011", "lvl": "target", "msg": "<6>[    1.177319] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:41:38.460586", "lvl": "target", "msg": "<6>[    1.178883] Using buffer write method"}
- {"dt": "2024-07-11T07:41:38.460957", "lvl": "target", "msg": "<7>[    1.179236] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:41:38.461368", "lvl": "target", "msg": "<5>[    1.179770] Concatenating MTD devices:"}
- {"dt": "2024-07-11T07:41:38.461757", "lvl": "target", "msg": "<5>[    1.180099] (0): \"0.flash\""}
- {"dt": "2024-07-11T07:41:38.461931", "lvl": "target", "msg": "<5>[    1.180376] (1): \"0.flash\""}
- {"dt": "2024-07-11T07:41:38.462198", "lvl": "target", "msg": "<5>[    1.180624] into device \"0.flash\""}
- {"dt": "2024-07-11T07:41:38.529967", "lvl": "target", "msg": "<6>[    1.248218] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:41:38.530162", "lvl": "target", "msg": "<6>[    1.248437] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-07-11T07:41:38.530313", "lvl": "target", "msg": "<6>[    1.248742] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:41:38.530489", "lvl": "target", "msg": "<6>[    1.248916] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-07-11T07:41:38.530732", "lvl": "target", "msg": "<6>[    1.249224] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-07-11T07:41:38.530929", "lvl": "target", "msg": "<6>[    1.249414] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-07-11T07:41:38.531232", "lvl": "target", "msg": "<6>[    1.249698] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-07-11T07:41:38.531534", "lvl": "target", "msg": "<6>[    1.249909] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-07-11T07:41:38.543544", "lvl": "target", "msg": "<6>[    1.261852] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-07-11T07:41:38.544712", "lvl": "target", "msg": "<6>[    1.262853] rtc-pl031 9010000.pl031: setting system clock to 2024-07-11T07:41:38 UTC (1720683698)"}
- {"dt": "2024-07-11T07:41:38.548870", "lvl": "target", "msg": "<6>[    1.267197] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-07-11T07:41:38.587339", "lvl": "target", "msg": "<6>[    1.305650] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-07-11T07:41:38.587602", "lvl": "target", "msg": "<6>[    1.306079] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-07-11T07:41:38.591244", "lvl": "target", "msg": "<6>[    1.309645] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-07-11T07:41:38.596341", "lvl": "target", "msg": "<6>[    1.314747] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-07-11T07:41:38.606579", "lvl": "target", "msg": "<6>[    1.324941] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-07-11T07:41:38.661145", "lvl": "target", "msg": "<6>[    1.379163] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2024-07-11T07:41:38.662864", "lvl": "target", "msg": "<6>[    1.381207] watchdog: Delayed init of the lockup detector failed: -19"}
- {"dt": "2024-07-11T07:41:38.663121", "lvl": "target", "msg": "<6>[    1.381556] watchdog: Hard watchdog permanently disabled"}
- {"dt": "2024-07-11T07:41:38.676303", "lvl": "target", "msg": "<6>[    1.394557]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2024-07-11T07:41:38.718735", "lvl": "target", "msg": "<6>[    1.436996] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-07-11T07:41:38.735463", "lvl": "target", "msg": "<6>[    1.453731] Segment Routing with IPv6"}
- {"dt": "2024-07-11T07:41:38.735939", "lvl": "target", "msg": "<6>[    1.454313] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-07-11T07:41:38.736391", "lvl": "target", "msg": "<6>[    1.454896] mip6: Mobile IPv6"}
- {"dt": "2024-07-11T07:41:38.736799", "lvl": "target", "msg": "<6>[    1.455246] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-07-11T07:41:38.738767", "lvl": "target", "msg": "<6>[    1.457067] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-07-11T07:41:38.739912", "lvl": "target", "msg": "<5>[    1.458219] Key type dns_resolver registered"}
- {"dt": "2024-07-11T07:41:38.802294", "lvl": "target", "msg": "<6>[    1.520518] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-07-11T07:41:38.803230", "lvl": "target", "msg": "<3>[    1.521554] Timer migration setup failed"}
- {"dt": "2024-07-11T07:41:38.803704", "lvl": "target", "msg": "<6>[    1.522227] registered taskstats version 1"}
- {"dt": "2024-07-11T07:41:38.805361", "lvl": "target", "msg": "<5>[    1.523707] Loading compiled-in X.509 certificates"}
- {"dt": "2024-07-11T07:41:38.819335", "lvl": "target", "msg": "<5>[    1.537421] Loaded X.509 cert 'Build time autogenerated kernel key: 86e0a6107f6c86850afde5a46e48d1e5c2263338'"}
- {"dt": "2024-07-11T07:41:38.854338", "lvl": "target", "msg": "<6>[    1.572636] Demotion targets for Node 0: null"}
- {"dt": "2024-07-11T07:41:38.855911", "lvl": "target", "msg": "<5>[    1.574298] Key type .fscrypt registered"}
- {"dt": "2024-07-11T07:41:38.856012", "lvl": "target", "msg": "<5>[    1.574477] Key type fscrypt-provisioning registered"}
- {"dt": "2024-07-11T07:41:38.857620", "lvl": "target", "msg": "<5>[    1.576024] Key type encrypted registered"}
- {"dt": "2024-07-11T07:41:38.857749", "lvl": "target", "msg": "<6>[    1.576237] AppArmor: AppArmor sha256 policy hashing enabled"}
- {"dt": "2024-07-11T07:41:38.858522", "lvl": "target", "msg": "<6>[    1.576933] ima: No TPM chip found, activating TPM-bypass!"}
- {"dt": "2024-07-11T07:41:38.858709", "lvl": "target", "msg": "<6>[    1.577198] ima: Allocated hash algorithm: sha256"}
- {"dt": "2024-07-11T07:41:38.861947", "lvl": "target", "msg": "<6>[    1.580284] ima: No architecture policies found"}
- {"dt": "2024-07-11T07:41:38.863104", "lvl": "target", "msg": "<6>[    1.581532] evm: Initialising EVM extended attributes:"}
- {"dt": "2024-07-11T07:41:38.863267", "lvl": "target", "msg": "<6>[    1.581782] evm: security.selinux"}
- {"dt": "2024-07-11T07:41:38.863596", "lvl": "target", "msg": "<6>[    1.582071] evm: security.SMACK64 (disabled)"}
- {"dt": "2024-07-11T07:41:38.863803", "lvl": "target", "msg": "<6>[    1.582281] evm: security.SMACK64EXEC (disabled)"}
- {"dt": "2024-07-11T07:41:38.864042", "lvl": "target", "msg": "<6>[    1.582494] evm: security.SMACK64TRANSMUTE (disabled)"}
- {"dt": "2024-07-11T07:41:38.864256", "lvl": "target", "msg": "<6>[    1.582730] evm: security.SMACK64MMAP (disabled)"}
- {"dt": "2024-07-11T07:41:38.864419", "lvl": "target", "msg": "<6>[    1.582941] evm: security.apparmor"}
- {"dt": "2024-07-11T07:41:38.864578", "lvl": "target", "msg": "<6>[    1.583110] evm: security.ima"}
- {"dt": "2024-07-11T07:41:38.864746", "lvl": "target", "msg": "<6>[    1.583263] evm: security.capability"}
- {"dt": "2024-07-11T07:41:38.865005", "lvl": "target", "msg": "<6>[    1.583449] evm: HMAC attrs: 0x1"}
- {"dt": "2024-07-11T07:41:38.873261", "lvl": "target", "msg": "<6>[    1.591601] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-07-11T07:41:38.880660", "lvl": "target", "msg": "<6>[    1.599039] clk: Disabling unused clocks"}
- {"dt": "2024-07-11T07:41:38.880999", "lvl": "target", "msg": "<6>[    1.599458] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-07-11T07:41:38.881592", "lvl": "target", "msg": "<6>[    1.600016] ALSA device list:"}
- {"dt": "2024-07-11T07:41:38.881733", "lvl": "target", "msg": "<6>[    1.600249]   No soundcards found."}
- {"dt": "2024-07-11T07:41:38.918248", "lvl": "target", "msg": "<6>[    1.636405] EXT4-fs (vda): mounted filesystem 6a4cd5c7-4ab5-45d4-8820-40d209833431 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-07-11T07:41:38.918729", "lvl": "target", "msg": "<6>[    1.637193] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-07-11T07:41:38.921442", "lvl": "target", "msg": "<6>[    1.639830] devtmpfs: mounted"}
- {"dt": "2024-07-11T07:41:38.984196", "lvl": "target", "msg": "<6>[    1.702431] Freeing unused kernel memory: 14016K"}
- {"dt": "2024-07-11T07:41:39.185541", "lvl": "target", "msg": "<6>[    1.903795] Checked W+X mappings: passed, no W+X pages found"}
- {"dt": "2024-07-11T07:41:39.187046", "lvl": "target", "msg": "<6>[    1.905377] Run /sbin/init as init process"}
- {"dt": "2024-07-11T07:41:39.187388", "lvl": "target", "msg": "<7>[    1.905834]   with arguments:"}
- {"dt": "2024-07-11T07:41:39.187966", "lvl": "target", "msg": "<7>[    1.906363]     /sbin/init"}
- {"dt": "2024-07-11T07:41:39.188110", "lvl": "target", "msg": "<7>[    1.906608]     verbose"}
- {"dt": "2024-07-11T07:41:39.188399", "lvl": "target", "msg": "<7>[    1.906824]   with environment:"}
- {"dt": "2024-07-11T07:41:39.188579", "lvl": "target", "msg": "<7>[    1.907068]     HOME=/"}
- {"dt": "2024-07-11T07:41:39.188867", "lvl": "target", "msg": "<7>[    1.907288]     TERM=linux"}
- {"dt": "2024-07-11T07:41:39.220591", "lvl": "target", "msg": "<6>[    1.938806] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist."}
- {"dt": "2024-07-11T07:41:39.660880", "lvl": "target", "msg": "<30>[    2.377808] systemd[1]: Inserted module 'autofs4'"}
- {"dt": "2024-07-11T07:41:39.851351", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:41:39.851753", "lvl": "target", "msg": "Welcome to [1mDebian GNU/Linux 12 (bookworm)[0m!"}
- {"dt": "2024-07-11T07:41:39.851821", "lvl": "target", "msg": "\r"}
- {"dt": "2024-07-11T07:41:41.510843", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2024-07-11T07:41:41.519730", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modpr\u2026lice[0m - Slice /system/modprobe.\r"}
- {"dt": "2024-07-11T07:41:41.526091", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-seria\u2026[0m - Slice /system/serial-getty.\r"}
- {"dt": "2024-07-11T07:41:41.531814", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2024-07-11T07:41:41.535251", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026quests to Console Directory Watch.\r"}
- {"dt": "2024-07-11T07:41:41.537570", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026 Requests to Wall Directory Watch.\r"}
- {"dt": "2024-07-11T07:41:41.544088", "lvl": "target", "msg": "[[0;32m  OK  [0m] Set up automount [0;1;39mproc-sys-\u2026rmats File System Automount Point.\r"}
- {"dt": "2024-07-11T07:41:41.545772", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2024-07-11T07:41:41.546887", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mcryptsetup.\u2026get[0m - Local Encrypted Volumes.\r"}
- {"dt": "2024-07-11T07:41:41.548313", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mintegrityse\u2026Local Integrity Protected Volumes.\r"}
- {"dt": "2024-07-11T07:41:41.549393", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2024-07-11T07:41:41.550392", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2024-07-11T07:41:41.551639", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2024-07-11T07:41:41.552605", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2024-07-11T07:41:41.553819", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mveritysetup\u2026 - Local Verity Protected Volumes.\r"}
- {"dt": "2024-07-11T07:41:41.557726", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initc\u2026 initctl Compatibility Named Pipe.\r"}
- {"dt": "2024-07-11T07:41:41.568542", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026socket[0m - Journal Audit Socket.\r"}
- {"dt": "2024-07-11T07:41:41.573127", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026t[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2024-07-11T07:41:41.577284", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket.\r"}
- {"dt": "2024-07-11T07:41:41.582328", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026.socket[0m - udev Control Socket.\r"}
- {"dt": "2024-07-11T07:41:41.585615", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026l.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2024-07-11T07:41:41.628526", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System...\r"}
- {"dt": "2024-07-11T07:41:41.666585", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount\u2026POSIX Message Queue File System...\r"}
- {"dt": "2024-07-11T07:41:41.718326", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.\u2026[0m - Kernel Debug File System...\r"}
- {"dt": "2024-07-11T07:41:41.798065", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracin\u2026[0m - Kernel Trace File System...\r"}
- {"dt": "2024-07-11T07:41:41.830002", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes\u2026ate List of Static Device Nodes...\r"}
- {"dt": "2024-07-11T07:41:41.859416", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs\u2026m - Load Kernel Module configfs...\r"}
- {"dt": "2024-07-11T07:41:41.950950", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@dm_mod.s\u2026[0m - Load Kernel Module dm_mod...\r"}
- {"dt": "2024-07-11T07:41:41.972942", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2024-07-11T07:41:42.001355", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_psto\u2026- Load Kernel Module efi_pstore...\r"}
- {"dt": "2024-07-11T07:41:42.070604", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.ser\u2026e[0m - Load Kernel Module fuse...\r"}
- {"dt": "2024-07-11T07:41:42.134312", "lvl": "target", "msg": "<4>[    4.852450] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log."}
- {"dt": "2024-07-11T07:41:42.135246", "lvl": "target", "msg": "<6>[    4.853670] device-mapper: uevent: version 1.0.3"}
- {"dt": "2024-07-11T07:41:42.140868", "lvl": "target", "msg": "<6>[    4.859215] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev"}
- {"dt": "2024-07-11T07:41:42.159072", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@loop.ser\u2026e[0m - Load Kernel Module loop...\r"}
- {"dt": "2024-07-11T07:41:42.272910", "lvl": "target", "msg": "<6>[    4.991192] fuse: init (API version 7.40)"}
- {"dt": "2024-07-11T07:41:42.282840", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2024-07-11T07:41:42.334831", "lvl": "target", "msg": "<6>[    5.053066] loop: module loaded"}
- {"dt": "2024-07-11T07:41:42.346527", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-l\u2026rvice[0m - Load Kernel Modules...\r"}
- {"dt": "2024-07-11T07:41:42.371321", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-f\u2026nt Root and Kernel File Systems...\r"}
- {"dt": "2024-07-11T07:41:42.454295", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trig\u2026[0m - Coldplug All udev Devices...\r"}
- {"dt": "2024-07-11T07:41:42.611282", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System.\r"}
- {"dt": "2024-07-11T07:41:42.626498", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[\u2026- POSIX Message Queue File System.\r"}
- {"dt": "2024-07-11T07:41:42.631244", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.m\u2026nt[0m - Kernel Debug File System.\r"}
- {"dt": "2024-07-11T07:41:42.634669", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing\u2026nt[0m - Kernel Trace File System.\r"}
- {"dt": "2024-07-11T07:41:42.643696", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes\u2026reate List of Static Device Nodes.\r"}
- {"dt": "2024-07-11T07:41:42.664191", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs\u2026[0m - Load Kernel Module configfs.\r"}
- {"dt": "2024-07-11T07:41:42.680827", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@dm_mod.s\u2026e[0m - Load Kernel Module dm_mod.\r"}
- {"dt": "2024-07-11T07:41:42.694491", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2024-07-11T07:41:42.707182", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_psto\u2026m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2024-07-11T07:41:42.724184", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2024-07-11T07:41:42.747189", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop.\r"}
- {"dt": "2024-07-11T07:41:42.762743", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-l\u2026service[0m - Load Kernel Modules.\r"}
- {"dt": "2024-07-11T07:41:42.777490", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-f\u2026ount Root and Kernel File Systems.\r"}
- {"dt": "2024-07-11T07:41:42.849833", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-conne\u2026[0m - FUSE Control File System...\r"}
- {"dt": "2024-07-11T07:41:42.922489", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config\u2026ernel Configuration File System...\r"}
- {"dt": "2024-07-11T07:41:43.050975", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-se\u2026ice[0m - Load/Save Random Seed...\r"}
- {"dt": "2024-07-11T07:41:43.158735", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.se\u2026ce[0m - Apply Kernel Variables...\r"}
- {"dt": "2024-07-11T07:41:43.218674", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.\u2026rvice[0m - Create System Users...\r"}
- {"dt": "2024-07-11T07:41:43.287298", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2024-07-11T07:41:43.370322", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connec\u2026nt[0m - FUSE Control File System.\r"}
- {"dt": "2024-07-11T07:41:43.378708", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.\u2026 Kernel Configuration File System.\r"}
- {"dt": "2024-07-11T07:41:43.397506", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-se\u2026rvice[0m - Load/Save Random Seed.\r"}
- {"dt": "2024-07-11T07:41:43.474454", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-f\u2026h Journal to Persistent Storage...\r"}
- {"dt": "2024-07-11T07:41:43.592888", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2024-07-11T07:41:43.660339", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2024-07-11T07:41:43.703105", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026ate Static Device Nodes in /dev...\r"}
- {"dt": "2024-07-11T07:41:43.858242", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-f\u2026ush Journal to Persistent Storage.\r"}
- {"dt": "2024-07-11T07:41:44.012239", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026reate Static Device Nodes in /dev.\r"}
- {"dt": "2024-07-11T07:41:44.015263", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pr\u2026reparation for Local File Systems.\r"}
- {"dt": "2024-07-11T07:41:44.017282", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2024-07-11T07:41:44.078736", "lvl": "target", "msg": "         Starting [0;1;39msystemd-binfmt.se\u2026et Up Additional Binary Formats...\r"}
- {"dt": "2024-07-11T07:41:44.115567", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026 Volatile Files and Directories...\r"}
- {"dt": "2024-07-11T07:41:44.178422", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.ser\u2026ger for Device Events and Files...\r"}
- {"dt": "2024-07-11T07:41:44.369915", "lvl": "target", "msg": "         Mounting [0;1;39mproc-sys-fs-binfm\u2026utable File Formats File System...\r"}
- {"dt": "2024-07-11T07:41:44.647639", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mproc-sys-fs-binfmt\u2026ecutable File Formats File System.\r"}
- {"dt": "2024-07-11T07:41:44.697835", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-binfmt.se\u2026 Set Up Additional Binary Formats.\r"}
- {"dt": "2024-07-11T07:41:44.717461", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026te Volatile Files and Directories.\r"}
- {"dt": "2024-07-11T07:41:44.756282", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026rd System Boot/Shutdown in UTMP...\r"}
- {"dt": "2024-07-11T07:41:45.021513", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.serv\u2026nager for Device Events and Files.\r"}
- {"dt": "2024-07-11T07:41:45.100643", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026cord System Boot/Shutdown in UTMP.\r"}
- {"dt": "2024-07-11T07:41:46.658960", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trig\u2026e[0m - Coldplug All udev Devices.\r"}
- {"dt": "2024-07-11T07:41:46.662152", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2024-07-11T07:41:46.668829", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2024-07-11T07:41:46.671687", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.\u2026 apt upgrade and clean activities.\r"}
- {"dt": "2024-07-11T07:41:46.673753", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.tim\u2026 Daily dpkg database backup timer.\r"}
- {"dt": "2024-07-11T07:41:46.679290", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer\u2026etadata Check for All Filesystems.\r"}
- {"dt": "2024-07-11T07:41:46.682984", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused blocks once a week.\r"}
- {"dt": "2024-07-11T07:41:46.685267", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-c\u2026 Cleanup of Temporary Directories.\r"}
- {"dt": "2024-07-11T07:41:46.686531", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2024-07-11T07:41:46.690395", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[\u2026- D-Bus System Message Bus Socket.\r"}
- {"dt": "2024-07-11T07:41:46.691826", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2024-07-11T07:41:46.693246", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2024-07-11T07:41:46.734664", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2024-07-11T07:41:46.786674", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.serv\u2026e ext4 Metadata Check Snapshots...\r"}
- {"dt": "2024-07-11T07:41:46.922739", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.se\u2026ice[0m - User Login Management...\r"}
- {"dt": "2024-07-11T07:41:46.998521", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sess\u2026vice[0m - Permit User Sessions...\r"}
- {"dt": "2024-07-11T07:41:47.084332", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2024-07-11T07:41:47.291695", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sess\u2026ervice[0m - Permit User Sessions.\r"}
- {"dt": "2024-07-11T07:41:47.376339", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2024-07-11T07:41:47.455325", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2024-07-11T07:41:47.511388", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAM\u2026ice[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2024-07-11T07:41:47.518643", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2024-07-11T07:41:47.717883", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.serv\u2026ine ext4 Metadata Check Snapshots.\r"}
- {"dt": "2024-07-11T07:41:48.039403", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2024-07-11T07:41:48.046666", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2024-07-11T07:41:48.056783", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2024-07-11T07:41:48.128994", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026 Record Runlevel Change in UTMP...\r"}
- {"dt": "2024-07-11T07:41:48.318233", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026 - Record Runlevel Change in UTMP.\r"}
- {"dt": "2024-07-11T07:41:49.501590", "lvl": "target", "msg": "\r"}
- {"dt": "2024-07-11T07:41:49.501970", "lvl": "target", "msg": "Debian GNU/Linux 12 runner-vwmj3eza-project-40964107-concurrent-1 ttyAMA0"}
- {"dt": "2024-07-11T07:41:49.502049", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:41:49.503192", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2024-07-11T07:41:49.503260", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "12.83", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2024-07-11T07:41:49.503359", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-07-11T07:41:49.503418", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2024-07-11T07:41:49.603682", "lvl": "input", "msg": "root"}
- {"dt": "2024-07-11T07:41:49.603929", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:41:49.604011", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:04:47)"}
- {"dt": "2024-07-11T07:41:49.604078", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:02:23)"}
- {"dt": "2024-07-11T07:41:49.604868", "lvl": "target", "msg": "runner-vwmj3eza-project-40964107-concurrent-1 login: root\r"}
- {"dt": "2024-07-11T07:41:50.017690", "lvl": "target", "msg": "Linux runner-vwmj3eza-project-40964107-concurrent-1 6.10.0-rc7-next-20240709 #1 SMP @1720521086 aarch64"}
- {"dt": "2024-07-11T07:41:50.021978", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:41:50.022303", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2024-07-11T07:41:50.022393", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2024-07-11T07:41:50.022469", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2024-07-11T07:41:50.022538", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:41:50.022855", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2024-07-11T07:41:50.023111", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2024-07-11T07:41:52.536099", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:41:52.536328", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:16) [common]"}
- {"dt": "2024-07-11T07:41:52.536407", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "15.87", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2024-07-11T07:41:52.536557", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:16) [common]"}
- {"dt": "2024-07-11T07:41:52.536650", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:04:44) [common]"}
- {"dt": "2024-07-11T07:41:52.536722", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:41:52.536780", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:41:52.536840", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2024-07-11T07:41:52.587008", "lvl": "input", "msg": ""}
- {"dt": "2024-07-11T07:41:52.587271", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:05:00)"}
- {"dt": "2024-07-11T07:41:52.587353", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:02:30)"}
- {"dt": "2024-07-11T07:41:52.589524", "lvl": "target", "msg": "[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# "}
- {"dt": "2024-07-11T07:41:52.598352", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:52.598526", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:04:44) [common]"}
- {"dt": "2024-07-11T07:41:52.598642", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:52.598743", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:16) [common]"}
- {"dt": "2024-07-11T07:41:52.598837", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T07:41:52.598933", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T07:41:52.599016", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-07-11T07:41:52.599101", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2024-07-11T07:41:52.699383", "lvl": "input", "msg": "#"}
- {"dt": "2024-07-11T07:41:52.699654", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:10:00)"}
- {"dt": "2024-07-11T07:41:52.700829", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# #"}
- {"dt": "2024-07-11T07:41:52.706619", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2024-07-11T07:41:52.706730", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2024-07-11T07:41:52.807016", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-07-11T07:41:52.808344", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# export SHELL=/bin/sh"}
- {"dt": "2024-07-11T07:41:52.812683", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2024-07-11T07:41:52.912978", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2024-07-11T07:41:52.914639", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# . /lava-1/environment"}
- {"dt": "2024-07-11T07:41:52.923380", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2024-07-11T07:41:53.023760", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2024-07-11T07:41:53.023989", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-07-11T07:41:53.024988", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# /lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2024-07-11T07:41:53.526004", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_commands"}
- {"dt": "2024-07-11T07:41:53.526954", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_commands"}
- {"dt": "2024-07-11T07:41:53.536230", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2024-07-11T07:41:53.581262", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2024-07-11T07:41:53.581599", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-07-11T07:41:53.582702", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2024-07-11T07:41:53.582973", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2024-07-11T07:41:53.583051", "lvl": "info", "msg": "Starting test lava.0_commands (1_1.1.3.1)"}
- {"dt": "2024-07-11T07:41:53.583142", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-07-11T07:41:53.583248", "lvl": "target", "msg": "+ lava-test-case command --shell dmesg"}
- {"dt": "2024-07-11T07:41:53.626924", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTTC command>"}
- {"dt": "2024-07-11T07:41:53.627285", "lvl": "debug", "msg": "Received signal: <STARTTC> command"}
- {"dt": "2024-07-11T07:41:53.672530", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-07-11T07:41:53.673342", "lvl": "target", "msg": "[    0.000000] Linux version 6.10.0-rc7-next-20240709 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP @1720521086"}
- {"dt": "2024-07-11T07:41:53.673447", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-07-11T07:41:53.673760", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-07-11T07:41:53.673860", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-07-11T07:41:53.674126", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-07-11T07:41:53.674577", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-07-11T07:41:53.674673", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-07-11T07:41:53.674937", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-07-11T07:41:53.675426", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:53.675886", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f5fb240-0x13f5fdfff]"}
- {"dt": "2024-07-11T07:41:53.675985", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-07-11T07:41:53.676354", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-07-11T07:41:53.676453", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-07-11T07:41:53.676887", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:53.676985", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-07-11T07:41:53.677330", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-07-11T07:41:53.677633", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:53.677964", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:41:53.678252", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fc000000 on node -1"}
- {"dt": "2024-07-11T07:41:53.678544", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-07-11T07:41:53.678643", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-07-11T07:41:53.679093", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-07-11T07:41:53.679191", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-07-11T07:41:53.679658", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-07-11T07:41:53.679971", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 34 pages/cpu s100440 r8192 d30632 u139264"}
- {"dt": "2024-07-11T07:41:53.680273", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s100440 r8192 d30632 u139264 alloc=34*4096"}
- {"dt": "2024-07-11T07:41:53.680363", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-07-11T07:41:53.680629", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-07-11T07:41:53.681078", "lvl": "target", "msg": "[    0.000000] CPU features: SYS_ID_AA64MMFR1_EL1[11:8]: forced to 0"}
- {"dt": "2024-07-11T07:41:53.681368", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:41:53.681697", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-07-11T07:41:53.681804", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-07-11T07:41:53.682281", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-07-11T07:41:53.682397", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-07-11T07:41:53.682671", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-07-11T07:41:53.682931", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-07-11T07:41:53.683698", "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 kvm-arm.mode=nvhe earlycon"}
- {"dt": "2024-07-11T07:41:53.684169", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-07-11T07:41:53.684492", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.684831", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.684929", "lvl": "target", "msg": "[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-07-11T07:41:53.685424", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-07-11T07:41:53.685517", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-07-11T07:41:53.685928", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off"}
- {"dt": "2024-07-11T07:41:53.686014", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-07-11T07:41:53.686519", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000f8000000-0x00000000fc000000] (64MB)"}
- {"dt": "2024-07-11T07:41:53.686803", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-07-11T07:41:53.686914", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 65265 entries in 255 pages"}
- {"dt": "2024-07-11T07:41:53.687389", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 255 pages with 8 groups"}
- {"dt": "2024-07-11T07:41:53.687710", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-07-11T07:41:53.687825", "lvl": "target", "msg": "[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-07-11T07:41:53.688516", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2024-07-11T07:41:53.688806", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:41:53.689103", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:41:53.689569", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-07-11T07:41:53.689855", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-07-11T07:41:53.690219", "lvl": "target", "msg": "[    0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-07-11T07:41:53.690580", "lvl": "target", "msg": "[    0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-07-11T07:41:53.690886", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-07-11T07:41:53.691173", "lvl": "target", "msg": "[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-07-11T07:41:53.691266", "lvl": "target", "msg": "[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-07-11T07:41:53.691795", "lvl": "target", "msg": "[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-07-11T07:41:53.691892", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-07-11T07:41:53.692282", "lvl": "target", "msg": "[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-07-11T07:41:53.692379", "lvl": "target", "msg": "[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-07-11T07:41:53.692891", "lvl": "target", "msg": "[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-07-11T07:41:53.692990", "lvl": "target", "msg": "[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-07-11T07:41:53.693532", "lvl": "target", "msg": "[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100210000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:41:53.693974", "lvl": "target", "msg": "[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100220000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:41:53.694077", "lvl": "target", "msg": "[    0.000000] GICv3: using LPI property table @0x0000000100230000"}
- {"dt": "2024-07-11T07:41:53.694607", "lvl": "target", "msg": "[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100240000"}
- {"dt": "2024-07-11T07:41:53.694919", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-07-11T07:41:53.695233", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-07-11T07:41:53.695722", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-07-11T07:41:53.696244", "lvl": "target", "msg": "[    0.000058] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-07-11T07:41:53.696669", "lvl": "target", "msg": "[    0.003197] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)"}
- {"dt": "2024-07-11T07:41:53.696768", "lvl": "target", "msg": "[    0.015064] Console: colour dummy device 80x25"}
- {"dt": "2024-07-11T07:41:53.697345", "lvl": "target", "msg": "[    0.023303] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-07-11T07:41:53.697442", "lvl": "target", "msg": "[    0.024219] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-07-11T07:41:53.697993", "lvl": "target", "msg": "[    0.028186] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,tomoyo,bpf,ima,evm"}
- {"dt": "2024-07-11T07:41:53.698092", "lvl": "target", "msg": "[    0.029628] landlock: Up and running."}
- {"dt": "2024-07-11T07:41:53.698432", "lvl": "target", "msg": "[    0.029790] Yama: becoming mindful."}
- {"dt": "2024-07-11T07:41:53.698522", "lvl": "target", "msg": "[    0.033040] AppArmor: AppArmor initialized"}
- {"dt": "2024-07-11T07:41:53.698784", "lvl": "target", "msg": "[    0.033264] TOMOYO Linux initialized"}
- {"dt": "2024-07-11T07:41:53.699059", "lvl": "target", "msg": "[    0.033924] LSM support for eBPF active"}
- {"dt": "2024-07-11T07:41:53.699544", "lvl": "target", "msg": "[    0.037675] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.699985", "lvl": "target", "msg": "[    0.038003] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.700273", "lvl": "target", "msg": "[    0.071456] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-07-11T07:41:53.700555", "lvl": "target", "msg": "[    0.085711] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-07-11T07:41:53.700871", "lvl": "target", "msg": "[    0.085948] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-07-11T07:41:53.700986", "lvl": "target", "msg": "[    0.096745] Platform MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:53.701467", "lvl": "target", "msg": "[    0.097404] PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:53.701566", "lvl": "target", "msg": "[    0.098044] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:41:53.701832", "lvl": "target", "msg": "[    0.101261] EFI services will not be available."}
- {"dt": "2024-07-11T07:41:53.702102", "lvl": "target", "msg": "[    0.103553] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-07-11T07:41:53.702380", "lvl": "target", "msg": "[    0.111235] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-07-11T07:41:53.702841", "lvl": "target", "msg": "[    0.114284] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-07-11T07:41:53.703158", "lvl": "target", "msg": "[    0.115588] GICv3: CPU1: using allocated LPI pending table @0x0000000100250000"}
- {"dt": "2024-07-11T07:41:53.703484", "lvl": "target", "msg": "[    0.118590] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-07-11T07:41:53.703813", "lvl": "target", "msg": "[    0.124532] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-07-11T07:41:53.704094", "lvl": "target", "msg": "[    0.125866] SMP: Total of 2 processors activated."}
- {"dt": "2024-07-11T07:41:53.704184", "lvl": "target", "msg": "[    0.126086] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-07-11T07:41:53.704680", "lvl": "target", "msg": "[    0.126453] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-07-11T07:41:53.704771", "lvl": "target", "msg": "[    0.126683] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-07-11T07:41:53.705234", "lvl": "target", "msg": "[    0.126862] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-07-11T07:41:53.705526", "lvl": "target", "msg": "[    0.127034] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-07-11T07:41:53.705927", "lvl": "target", "msg": "[    0.127253] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-07-11T07:41:53.706229", "lvl": "target", "msg": "[    0.127551] CPU features: detected: Common not Private translations"}
- {"dt": "2024-07-11T07:41:53.706522", "lvl": "target", "msg": "[    0.127751] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-07-11T07:41:53.706880", "lvl": "target", "msg": "[    0.128007] CPU features: detected: Data cache clean to Point of Deep Persistence"}
- {"dt": "2024-07-11T07:41:53.707225", "lvl": "target", "msg": "[    0.128240] CPU features: detected: Data cache clean to Point of Persistence"}
- {"dt": "2024-07-11T07:41:53.707579", "lvl": "target", "msg": "[    0.128452] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-07-11T07:41:53.707883", "lvl": "target", "msg": "[    0.128692] CPU features: detected: E0PD"}
- {"dt": "2024-07-11T07:41:53.708222", "lvl": "target", "msg": "[    0.128868] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-07-11T07:41:53.708542", "lvl": "target", "msg": "[    0.129140] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-07-11T07:41:53.708832", "lvl": "target", "msg": "[    0.129406] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-07-11T07:41:53.709016", "lvl": "target", "msg": "[    0.129713] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:41:53.709502", "lvl": "target", "msg": "[    0.130177] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-07-11T07:41:53.709656", "lvl": "target", "msg": "[    0.130486] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-07-11T07:41:53.710133", "lvl": "target", "msg": "[    0.130780] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-07-11T07:41:53.710455", "lvl": "target", "msg": "[    0.131006] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2024-07-11T07:41:53.710755", "lvl": "target", "msg": "[    0.131297] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-07-11T07:41:53.711052", "lvl": "target", "msg": "[    0.131524] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-07-11T07:41:53.711359", "lvl": "target", "msg": "[    0.131708] CPU features: detected: Random Number Generator"}
- {"dt": "2024-07-11T07:41:53.711793", "lvl": "target", "msg": "[    0.131900] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-07-11T07:41:53.712082", "lvl": "target", "msg": "[    0.132192] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-07-11T07:41:53.712480", "lvl": "target", "msg": "[    0.132438] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2024-07-11T07:41:53.712796", "lvl": "target", "msg": "[    0.132713] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-07-11T07:41:53.713123", "lvl": "target", "msg": "[    0.132960] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-07-11T07:41:53.713217", "lvl": "target", "msg": "[    0.133158] CPU features: detected: FA64"}
- {"dt": "2024-07-11T07:41:53.713734", "lvl": "target", "msg": "[    0.133292] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-07-11T07:41:53.714024", "lvl": "target", "msg": "[    0.133506] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-07-11T07:41:53.714319", "lvl": "target", "msg": "[    0.144455] alternatives: applying system-wide alternatives"}
- {"dt": "2024-07-11T07:41:53.714692", "lvl": "target", "msg": "[    0.162328] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-07-11T07:41:53.715023", "lvl": "target", "msg": "[    0.163327] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:41:53.715312", "lvl": "target", "msg": "[    0.163645] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-07-11T07:41:53.715672", "lvl": "target", "msg": "[    0.165222] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-07-11T07:41:53.716136", "lvl": "target", "msg": "[    0.165529] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:41:53.716242", "lvl": "target", "msg": "[    0.165819] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-07-11T07:41:53.716713", "lvl": "target", "msg": "[    0.198035] node 0 deferred pages initialised in 8ms"}
- {"dt": "2024-07-11T07:41:53.717279", "lvl": "target", "msg": "[    0.199282] Memory: 3920176K/4194304K available (20096K kernel code, 6226K rwdata, 12872K rodata, 14016K init, 981K bss, 204312K reserved, 65536K cma-reserved)"}
- {"dt": "2024-07-11T07:41:53.717380", "lvl": "target", "msg": "[    0.216698] devtmpfs: initialized"}
- {"dt": "2024-07-11T07:41:53.717906", "lvl": "target", "msg": "[    0.247164] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-07-11T07:41:53.718214", "lvl": "target", "msg": "[    0.248541] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.718541", "lvl": "target", "msg": "[    0.251260] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-07-11T07:41:53.718638", "lvl": "target", "msg": "[    0.251600] 0 pages in range for non-PLT usage"}
- {"dt": "2024-07-11T07:41:53.718928", "lvl": "target", "msg": "[    0.251681] 510672 pages in range for PLT usage"}
- {"dt": "2024-07-11T07:41:53.719208", "lvl": "target", "msg": "[    0.255751] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-07-11T07:41:53.719753", "lvl": "target", "msg": "[    0.265901] DMI not present or invalid."}
- {"dt": "2024-07-11T07:41:53.720057", "lvl": "target", "msg": "[    0.283096] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-07-11T07:41:53.720422", "lvl": "target", "msg": "[    0.296656] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:53.720788", "lvl": "target", "msg": "[    0.298798] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:53.721161", "lvl": "target", "msg": "[    0.301376] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-07-11T07:41:53.721459", "lvl": "target", "msg": "[    0.302099] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-07-11T07:41:53.721819", "lvl": "target", "msg": "[    0.304926] audit: type=2000 audit(0.272:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-07-11T07:41:53.722146", "lvl": "target", "msg": "[    0.316841] thermal_sys: Registered thermal governor 'fair_share'"}
- {"dt": "2024-07-11T07:41:53.722486", "lvl": "target", "msg": "[    0.316966] thermal_sys: Registered thermal governor 'bang_bang'"}
- {"dt": "2024-07-11T07:41:53.722824", "lvl": "target", "msg": "[    0.317330] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-07-11T07:41:53.723154", "lvl": "target", "msg": "[    0.317806] thermal_sys: Registered thermal governor 'user_space'"}
- {"dt": "2024-07-11T07:41:53.723500", "lvl": "target", "msg": "[    0.318136] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-07-11T07:41:53.723857", "lvl": "target", "msg": "[    0.319876] cpuidle: using governor ladder"}
- {"dt": "2024-07-11T07:41:53.723954", "lvl": "target", "msg": "[    0.320924] cpuidle: using governor menu"}
- {"dt": "2024-07-11T07:41:53.724457", "lvl": "target", "msg": "[    0.324548] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-07-11T07:41:53.724739", "lvl": "target", "msg": "[    0.325779] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-07-11T07:41:53.724834", "lvl": "target", "msg": "[    0.345989] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-07-11T07:41:53.725415", "lvl": "target", "msg": "[    0.497282] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-07-11T07:41:53.725512", "lvl": "target", "msg": "[    0.499105] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:41:53.725981", "lvl": "target", "msg": "[    0.499746] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:41:53.726331", "lvl": "target", "msg": "[    0.524038] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:53.726490", "lvl": "target", "msg": "[    0.524385] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-07-11T07:41:53.726971", "lvl": "target", "msg": "[    0.524677] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:53.727096", "lvl": "target", "msg": "[    0.524971] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-07-11T07:41:53.727613", "lvl": "target", "msg": "[    0.525247] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:53.727963", "lvl": "target", "msg": "[    0.525530] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-07-11T07:41:53.728297", "lvl": "target", "msg": "[    0.526116] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:41:53.728649", "lvl": "target", "msg": "[    0.526417] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-07-11T07:41:53.729002", "lvl": "target", "msg": "[    0.537876] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-07-11T07:41:53.729342", "lvl": "target", "msg": "[    0.546626] ACPI: Interpreter disabled."}
- {"dt": "2024-07-11T07:41:53.729717", "lvl": "target", "msg": "[    0.563022] iommu: Default domain type: Translated"}
- {"dt": "2024-07-11T07:41:53.730111", "lvl": "target", "msg": "[    0.563309] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-07-11T07:41:53.730392", "lvl": "target", "msg": "[    0.567293] SCSI subsystem initialized"}
- {"dt": "2024-07-11T07:41:53.730707", "lvl": "target", "msg": "[    0.572339] mc: Linux media interface: v0.10"}
- {"dt": "2024-07-11T07:41:53.731079", "lvl": "target", "msg": "[    0.572865] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-07-11T07:41:53.731432", "lvl": "target", "msg": "[    0.573421] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-07-11T07:41:53.731916", "lvl": "target", "msg": "[    0.573667] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-07-11T07:41:53.732195", "lvl": "target", "msg": "[    0.574192] PTP clock support registered"}
- {"dt": "2024-07-11T07:41:53.732280", "lvl": "target", "msg": "[    0.575506] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-07-11T07:41:53.732612", "lvl": "target", "msg": "[    0.577606] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-07-11T07:41:53.732694", "lvl": "target", "msg": "[    0.583491] FPGA manager framework"}
- {"dt": "2024-07-11T07:41:53.732948", "lvl": "target", "msg": "[    0.584289] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-07-11T07:41:53.733206", "lvl": "target", "msg": "[    0.593931] NetLabel: Initializing"}
- {"dt": "2024-07-11T07:41:53.733516", "lvl": "target", "msg": "[    0.594157] NetLabel:  domain hash size = 128"}
- {"dt": "2024-07-11T07:41:53.733785", "lvl": "target", "msg": "[    0.594378] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-07-11T07:41:53.734049", "lvl": "target", "msg": "[    0.595675] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-07-11T07:41:53.734131", "lvl": "target", "msg": "[    0.597804] vgaarb: loaded"}
- {"dt": "2024-07-11T07:41:53.734515", "lvl": "target", "msg": "[    0.602251] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-07-11T07:41:53.734599", "lvl": "target", "msg": "[    0.608161] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-07-11T07:41:53.735030", "lvl": "target", "msg": "[    0.608570] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-07-11T07:41:53.735113", "lvl": "target", "msg": "[    0.612204] netfs: FS-Cache loaded"}
- {"dt": "2024-07-11T07:41:53.735470", "lvl": "target", "msg": "[    0.617184] AppArmor: AppArmor Filesystem Enabled"}
- {"dt": "2024-07-11T07:41:53.735771", "lvl": "target", "msg": "[    0.617800] pnp: PnP ACPI: disabled"}
- {"dt": "2024-07-11T07:41:53.735857", "lvl": "target", "msg": "[    0.656685] NET: Registered PF_INET protocol family"}
- {"dt": "2024-07-11T07:41:53.736328", "lvl": "target", "msg": "[    0.658727] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.736673", "lvl": "target", "msg": "[    0.668965] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.736997", "lvl": "target", "msg": "[    0.669660] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.737330", "lvl": "target", "msg": "[    0.670268] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.737649", "lvl": "target", "msg": "[    0.671624] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.737942", "lvl": "target", "msg": "[    0.672408] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-07-11T07:41:53.738259", "lvl": "target", "msg": "[    0.674667] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.738558", "lvl": "target", "msg": "[    0.675490] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.738868", "lvl": "target", "msg": "[    0.676096] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:41:53.739132", "lvl": "target", "msg": "[    0.677725] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-07-11T07:41:53.739213", "lvl": "target", "msg": "[    0.678471] NET: Registered PF_XDP protocol family"}
- {"dt": "2024-07-11T07:41:53.739663", "lvl": "target", "msg": "[    0.678878] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-07-11T07:41:53.739931", "lvl": "target", "msg": "[    0.691922] kvm [1]: nv: 529 coarse grained trap handlers"}
- {"dt": "2024-07-11T07:41:53.740028", "lvl": "target", "msg": "[    0.693146] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2024-07-11T07:41:53.740281", "lvl": "target", "msg": "[    0.694348] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-07-11T07:41:53.740637", "lvl": "target", "msg": "[    0.703918] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-07-11T07:41:53.740718", "lvl": "target", "msg": "[    0.704283] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-07-11T07:41:53.741147", "lvl": "target", "msg": "[    0.704561] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-07-11T07:41:53.741228", "lvl": "target", "msg": "[    0.705596] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-07-11T07:41:53.741479", "lvl": "target", "msg": "[    0.706391] kvm [1]: Hyp nVHE mode initialized successfully"}
- {"dt": "2024-07-11T07:41:53.741734", "lvl": "target", "msg": "[    0.712860] Initialise system trusted keyrings"}
- {"dt": "2024-07-11T07:41:53.742054", "lvl": "target", "msg": "[    0.714611] Key type blacklist registered"}
- {"dt": "2024-07-11T07:41:53.742311", "lvl": "target", "msg": "[    0.715952] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-07-11T07:41:53.742596", "lvl": "target", "msg": "[    0.725193] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-07-11T07:41:53.742677", "lvl": "target", "msg": "[    0.727798] integrity: Platform Keyring initialized"}
- {"dt": "2024-07-11T07:41:53.742928", "lvl": "target", "msg": "[    0.728283] integrity: Machine keyring initialized"}
- {"dt": "2024-07-11T07:41:53.743182", "lvl": "target", "msg": "[    0.798144] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-07-11T07:41:53.743660", "lvl": "target", "msg": "[    0.798561] Key type asymmetric registered"}
- {"dt": "2024-07-11T07:41:53.743923", "lvl": "target", "msg": "[    0.798817] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-07-11T07:41:53.744244", "lvl": "target", "msg": "[    0.799439] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)"}
- {"dt": "2024-07-11T07:41:53.744324", "lvl": "target", "msg": "[    0.801099] io scheduler mq-deadline registered"}
- {"dt": "2024-07-11T07:41:53.744592", "lvl": "target", "msg": "[    0.862421] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-07-11T07:41:53.745064", "lvl": "target", "msg": "[    0.876031] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-07-11T07:41:53.745432", "lvl": "target", "msg": "[    0.876981] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-07-11T07:41:53.745792", "lvl": "target", "msg": "[    0.877805] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-07-11T07:41:53.746150", "lvl": "target", "msg": "[    0.878483] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-07-11T07:41:53.746500", "lvl": "target", "msg": "[    0.879465] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-07-11T07:41:53.746873", "lvl": "target", "msg": "[    0.880359] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-07-11T07:41:53.747177", "lvl": "target", "msg": "[    0.882240] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-07-11T07:41:53.747440", "lvl": "target", "msg": "[    0.882721] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-07-11T07:41:53.747814", "lvl": "target", "msg": "[    0.883006] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:41:53.748114", "lvl": "target", "msg": "[    0.883235] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:41:53.748416", "lvl": "target", "msg": "[    0.883507] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:41:53.748766", "lvl": "target", "msg": "[    0.885038] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-07-11T07:41:53.749068", "lvl": "target", "msg": "[    0.890740] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:41:53.749390", "lvl": "target", "msg": "[    0.890981] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:41:53.749750", "lvl": "target", "msg": "[    0.891179] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:41:53.750071", "lvl": "target", "msg": "[    1.090396] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-07-11T07:41:53.750150", "lvl": "target", "msg": "[    1.105906] Serial: AMBA driver"}
- {"dt": "2024-07-11T07:41:53.750578", "lvl": "target", "msg": "[    1.109652] msm_serial: driver initialized"}
- {"dt": "2024-07-11T07:41:53.750664", "lvl": "target", "msg": "[    1.111804] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-07-11T07:41:53.750928", "lvl": "target", "msg": "[    1.112854] STM32 USART driver initialized"}
- {"dt": "2024-07-11T07:41:53.751376", "lvl": "target", "msg": "[    1.127321] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-07-11T07:41:53.751850", "lvl": "target", "msg": "[    1.135339] virtio_blk virtio0: [vda] 6059648 512-byte logical blocks (3.10 GB/2.89 GiB)"}
- {"dt": "2024-07-11T07:41:53.751932", "lvl": "target", "msg": "[    1.156268] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-07-11T07:41:53.752476", "lvl": "target", "msg": "[    1.167119] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-07-11T07:41:53.752886", "lvl": "target", "msg": "[    1.170285] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:41:53.753150", "lvl": "target", "msg": "[    1.171636] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:41:53.753230", "lvl": "target", "msg": "[    1.173266] Using buffer write method"}
- {"dt": "2024-07-11T07:41:53.753724", "lvl": "target", "msg": "[    1.174375] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:41:53.754091", "lvl": "target", "msg": "[    1.175173] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-07-11T07:41:53.754506", "lvl": "target", "msg": "[    1.176595] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:41:53.754772", "lvl": "target", "msg": "[    1.177319] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:41:53.754850", "lvl": "target", "msg": "[    1.178883] Using buffer write method"}
- {"dt": "2024-07-11T07:41:53.755344", "lvl": "target", "msg": "[    1.179236] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:41:53.755609", "lvl": "target", "msg": "[    1.179770] Concatenating MTD devices:"}
- {"dt": "2024-07-11T07:41:53.755870", "lvl": "target", "msg": "[    1.180099] (0): \"0.flash\""}
- {"dt": "2024-07-11T07:41:53.755949", "lvl": "target", "msg": "[    1.180376] (1): \"0.flash\""}
- {"dt": "2024-07-11T07:41:53.756198", "lvl": "target", "msg": "[    1.180624] into device \"0.flash\""}
- {"dt": "2024-07-11T07:41:53.756578", "lvl": "target", "msg": "[    1.248218] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:41:53.756847", "lvl": "target", "msg": "[    1.248437] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-07-11T07:41:53.757119", "lvl": "target", "msg": "[    1.248742] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:41:53.757421", "lvl": "target", "msg": "[    1.248916] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-07-11T07:41:53.757717", "lvl": "target", "msg": "[    1.249224] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-07-11T07:41:53.758020", "lvl": "target", "msg": "[    1.249414] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-07-11T07:41:53.758309", "lvl": "target", "msg": "[    1.249698] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-07-11T07:41:53.758649", "lvl": "target", "msg": "[    1.249909] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-07-11T07:41:53.758945", "lvl": "target", "msg": "[    1.261852] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-07-11T07:41:53.759342", "lvl": "target", "msg": "[    1.262853] rtc-pl031 9010000.pl031: setting system clock to 2024-07-11T07:41:38 UTC (1720683698)"}
- {"dt": "2024-07-11T07:41:53.759675", "lvl": "target", "msg": "[    1.267197] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-07-11T07:41:53.759994", "lvl": "target", "msg": "[    1.305650] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-07-11T07:41:53.760257", "lvl": "target", "msg": "[    1.306079] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-07-11T07:41:53.760565", "lvl": "target", "msg": "[    1.309645] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-07-11T07:41:53.760872", "lvl": "target", "msg": "[    1.314747] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-07-11T07:41:53.761177", "lvl": "target", "msg": "[    1.324941] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-07-11T07:41:53.761585", "lvl": "target", "msg": "[    1.379163] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2024-07-11T07:41:53.761888", "lvl": "target", "msg": "[    1.381207] watchdog: Delayed init of the lockup detector failed: -19"}
- {"dt": "2024-07-11T07:41:53.762180", "lvl": "target", "msg": "[    1.381556] watchdog: Hard watchdog permanently disabled"}
- {"dt": "2024-07-11T07:41:53.762528", "lvl": "target", "msg": "[    1.394557]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2024-07-11T07:41:53.762800", "lvl": "target", "msg": "[    1.436996] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-07-11T07:41:53.762880", "lvl": "target", "msg": "[    1.453731] Segment Routing with IPv6"}
- {"dt": "2024-07-11T07:41:53.763137", "lvl": "target", "msg": "[    1.454313] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-07-11T07:41:53.763582", "lvl": "target", "msg": "[    1.454896] mip6: Mobile IPv6"}
- {"dt": "2024-07-11T07:41:53.763853", "lvl": "target", "msg": "[    1.455246] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-07-11T07:41:53.763949", "lvl": "target", "msg": "[    1.457067] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-07-11T07:41:53.764380", "lvl": "target", "msg": "[    1.458219] Key type dns_resolver registered"}
- {"dt": "2024-07-11T07:41:53.764733", "lvl": "target", "msg": "[    1.520518] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-07-11T07:41:53.764816", "lvl": "target", "msg": "[    1.521554] Timer migration setup failed"}
- {"dt": "2024-07-11T07:41:53.765073", "lvl": "target", "msg": "[    1.522227] registered taskstats version 1"}
- {"dt": "2024-07-11T07:41:53.765344", "lvl": "target", "msg": "[    1.523707] Loading compiled-in X.509 certificates"}
- {"dt": "2024-07-11T07:41:53.765923", "lvl": "target", "msg": "[    1.537421] Loaded X.509 cert 'Build time autogenerated kernel key: 86e0a6107f6c86850afde5a46e48d1e5c2263338'"}
- {"dt": "2024-07-11T07:41:53.766007", "lvl": "target", "msg": "[    1.572636] Demotion targets for Node 0: null"}
- {"dt": "2024-07-11T07:41:53.766287", "lvl": "target", "msg": "[    1.574298] Key type .fscrypt registered"}
- {"dt": "2024-07-11T07:41:53.766549", "lvl": "target", "msg": "[    1.574477] Key type fscrypt-provisioning registered"}
- {"dt": "2024-07-11T07:41:53.766809", "lvl": "target", "msg": "[    1.576024] Key type encrypted registered"}
- {"dt": "2024-07-11T07:41:53.767245", "lvl": "target", "msg": "[    1.576237] AppArmor: AppArmor sha256 policy hashing enabled"}
- {"dt": "2024-07-11T07:41:53.767567", "lvl": "target", "msg": "[    1.576933] ima: No TPM chip found, activating TPM-bypass!"}
- {"dt": "2024-07-11T07:41:53.768046", "lvl": "target", "msg": "[    1.577198] ima: Allocated hash algorithm: sha256"}
- {"dt": "2024-07-11T07:41:53.768141", "lvl": "target", "msg": "[    1.580284] ima: No architecture policies found"}
- {"dt": "2024-07-11T07:41:53.768594", "lvl": "target", "msg": "[    1.581532] evm: Initialising EVM extended attributes:"}
- {"dt": "2024-07-11T07:41:53.768678", "lvl": "target", "msg": "[    1.581782] evm: security.selinux"}
- {"dt": "2024-07-11T07:41:53.768931", "lvl": "target", "msg": "[    1.582071] evm: security.SMACK64 (disabled)"}
- {"dt": "2024-07-11T07:41:53.769188", "lvl": "target", "msg": "[    1.582281] evm: security.SMACK64EXEC (disabled)"}
- {"dt": "2024-07-11T07:41:53.769448", "lvl": "target", "msg": "[    1.582494] evm: security.SMACK64TRANSMUTE (disabled)"}
- {"dt": "2024-07-11T07:41:53.769717", "lvl": "target", "msg": "[    1.582730] evm: security.SMACK64MMAP (disabled)"}
- {"dt": "2024-07-11T07:41:53.769974", "lvl": "target", "msg": "[    1.582941] evm: security.apparmor"}
- {"dt": "2024-07-11T07:41:53.770296", "lvl": "target", "msg": "[    1.583110] evm: security.ima"}
- {"dt": "2024-07-11T07:41:53.770379", "lvl": "target", "msg": "[    1.583263] evm: security.capability"}
- {"dt": "2024-07-11T07:41:53.770629", "lvl": "target", "msg": "[    1.583449] evm: HMAC attrs: 0x1"}
- {"dt": "2024-07-11T07:41:53.771084", "lvl": "target", "msg": "[    1.591601] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-07-11T07:41:53.771167", "lvl": "target", "msg": "[    1.599039] clk: Disabling unused clocks"}
- {"dt": "2024-07-11T07:41:53.771670", "lvl": "target", "msg": "[    1.599458] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-07-11T07:41:53.771751", "lvl": "target", "msg": "[    1.600016] ALSA device list:"}
- {"dt": "2024-07-11T07:41:53.772002", "lvl": "target", "msg": "[    1.600249]   No soundcards found."}
- {"dt": "2024-07-11T07:41:53.772566", "lvl": "target", "msg": "[    1.636405] EXT4-fs (vda): mounted filesystem 6a4cd5c7-4ab5-45d4-8820-40d209833431 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-07-11T07:41:53.772844", "lvl": "target", "msg": "[    1.637193] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-07-11T07:41:53.772926", "lvl": "target", "msg": "[    1.639830] devtmpfs: mounted"}
- {"dt": "2024-07-11T07:41:53.773354", "lvl": "target", "msg": "[    1.702431] Freeing unused kernel memory: 14016K"}
- {"dt": "2024-07-11T07:41:53.773624", "lvl": "target", "msg": "[    1.903795] Checked W+X mappings: passed, no W+X pages found"}
- {"dt": "2024-07-11T07:41:53.773887", "lvl": "target", "msg": "[    1.905377] Run /sbin/init as init process"}
- {"dt": "2024-07-11T07:41:53.773967", "lvl": "target", "msg": "[    1.905834]   with arguments:"}
- {"dt": "2024-07-11T07:41:53.774217", "lvl": "target", "msg": "[    1.906363]     /sbin/init"}
- {"dt": "2024-07-11T07:41:53.774510", "lvl": "target", "msg": "[    1.906608]     verbose"}
- {"dt": "2024-07-11T07:41:53.774592", "lvl": "target", "msg": "[    1.906824]   with environment:"}
- {"dt": "2024-07-11T07:41:53.774843", "lvl": "target", "msg": "[    1.907068]     HOME=/"}
- {"dt": "2024-07-11T07:41:53.775139", "lvl": "target", "msg": "[    1.907288]     TERM=linux"}
- {"dt": "2024-07-11T07:41:53.775549", "lvl": "target", "msg": "[    1.938806] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist."}
- {"dt": "2024-07-11T07:41:53.775855", "lvl": "target", "msg": "[    2.377808] systemd[1]: Inserted module 'autofs4'"}
- {"dt": "2024-07-11T07:41:53.776367", "lvl": "target", "msg": "[    4.852450] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log."}
- {"dt": "2024-07-11T07:41:53.776448", "lvl": "target", "msg": "[    4.853670] device-mapper: uevent: version 1.0.3"}
- {"dt": "2024-07-11T07:41:53.777020", "lvl": "target", "msg": "[    4.859215] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev"}
- {"dt": "2024-07-11T07:41:53.777104", "lvl": "target", "msg": "[    4.991192] fuse: init (API version 7.40)"}
- {"dt": "2024-07-11T07:41:53.777356", "lvl": "target", "msg": "[    5.053066] loop: module loaded"}
- {"dt": "2024-07-11T07:41:53.783711", "lvl": "debug", "msg": "Received signal: <ENDTC> command"}
- {"dt": "2024-07-11T07:41:53.783886", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDTC command>"}
- {"dt": "2024-07-11T07:41:53.824352", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=command RESULT=pass>"}
- {"dt": "2024-07-11T07:41:53.824723", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=command RESULT=pass"}
- {"dt": "2024-07-11T07:41:53.824866", "lvl": "results", "msg": {"definition": "0_commands", "case": "command", "result": "pass", "starttc": ! "752", "endtc": ! "1099"}}
- {"dt": "2024-07-11T07:41:53.828747", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-07-11T07:41:53.829022", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2024-07-11T07:41:53.829110", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-07-11T07:41:53.829167", "lvl": "info", "msg": "Ending test lava.0_commands (1_1.1.3.1), duration 0.25"}
- {"dt": "2024-07-11T07:41:53.829254", "lvl": "results", "msg": {"definition": "lava", "case": "0_commands", "uuid": "1_1.1.3.1", "repository": {"metadata": {"format": "lava-test test definition 1.0", "name": "commands", "description": "Run commands"}, "run": {"steps": ["lava-test-case command --shell dmesg"]}}, "path": "command", "duration": "0.25", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2024-07-11T07:41:53.829425", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2024-07-11T07:41:53.839059", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-07-11T07:41:53.839336", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-07-11T07:41:53.839481", "lvl": "debug", "msg": "command: pass\n"}
- {"dt": "2024-07-11T07:41:53.839618", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:53.839740", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:53.839857", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:53.839969", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-07-11T07:41:53.840034", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:41:53.840092", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:41:53.840216", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:41:53.845093", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:41:53.845413", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:41:53.845821", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:41:54.841257", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-07-11T07:41:54.841433", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-07-11T07:41:54.941714", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-07-11T07:41:54.942264", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-07-11T07:41:54.942422", "lvl": "target", "msg": "[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# poweroff"}
- {"dt": "2024-07-11T07:41:55.042799", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:55.042999", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-07-11T07:41:55.043093", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:41:55.043202", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-07-11T07:41:55.043303", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/kernel"}
- {"dt": "2024-07-11T07:41:55.050747", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs"}
- {"dt": "2024-07-11T07:41:55.207822", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.modules"}
- {"dt": "2024-07-11T07:41:55.226499", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1/deployimages-6jy1hgj1/rootfs.overlay-00"}
- {"dt": "2024-07-11T07:41:55.229188", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-1n8tn7lz-1"}
- {"dt": "2024-07-11T07:41:55.230349", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-07-11T07:41:55.230430", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
