- {"dt": "2024-07-11T07:38:08.068173", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-07-11T07:38:08.068275", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-07-11T07:38:08.068383", "lvl": "info", "msg": "Start time: 2024-07-11 07:38:08.068373+00:00 (UTC)"}
- {"dt": "2024-07-11T07:38:08.069285", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2j3NWZxdeVbgIPF4MgXdEwDRflD/Image.gz exists"}
- {"dt": "2024-07-11T07:38:08.466808", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-07-11T07:38:09.049179", "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:38:09.049471", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2j3NWZxdeVbgIPF4MgXdEwDRflD/modules.tar.xz exists"}
- {"dt": "2024-07-11T07:38:09.172060", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2024-07-11T07:38:09.840529", "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:38:10.290901", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-07-11T07:38:10.291145", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-07-11T07:38:10.291216", "lvl": "warning", "msg": "Missing image arg for rootfs.overlay-00"}
- {"dt": "2024-07-11T07:38:10.291372", "lvl": "info", "msg": "validate duration: 2.22"}
- {"dt": "2024-07-11T07:38:10.291434", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-07-11T07:38:10.291556", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.291633", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.291839", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_"}
- {"dt": "2024-07-11T07:38:10.291998", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin"}
- {"dt": "2024-07-11T07:38:10.292105", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/tests"}
- {"dt": "2024-07-11T07:38:10.292209", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/results"}
- {"dt": "2024-07-11T07:38:10.292290", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-add-keys"}
- {"dt": "2024-07-11T07:38:10.292424", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-add-sources"}
- {"dt": "2024-07-11T07:38:10.292554", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-07-11T07:38:10.292680", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-07-11T07:38:10.292809", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-common-functions"}
- {"dt": "2024-07-11T07:38:10.292932", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-07-11T07:38:10.293056", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-install-packages"}
- {"dt": "2024-07-11T07:38:10.293180", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-07-11T07:38:10.293300", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-os-build"}
- {"dt": "2024-07-11T07:38:10.293425", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-07-11T07:38:10.293549", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-07-11T07:38:10.293683", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-target-ip"}
- {"dt": "2024-07-11T07:38:10.293804", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-target-mac"}
- {"dt": "2024-07-11T07:38:10.293926", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-target-storage"}
- {"dt": "2024-07-11T07:38:10.294052", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-case"}
- {"dt": "2024-07-11T07:38:10.294175", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-event"}
- {"dt": "2024-07-11T07:38:10.294312", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-07-11T07:38:10.294438", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-raise"}
- {"dt": "2024-07-11T07:38:10.294563", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-reference"}
- {"dt": "2024-07-11T07:38:10.294686", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-runner"}
- {"dt": "2024-07-11T07:38:10.294809", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-set"}
- {"dt": "2024-07-11T07:38:10.295002", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-test-shell"}
- {"dt": "2024-07-11T07:38:10.295239", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-07-11T07:38:10.295517", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-07-11T07:38:10.295734", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/environment"}
- {"dt": "2024-07-11T07:38:10.295902", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-07-11T07:38:10.295998", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-07-11T07:38:10.296084", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=10.0.2.100"}
- {"dt": "2024-07-11T07:38:10.296230", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.296322", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-07-11T07:38:10.296425", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.296533", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.296613", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-07-11T07:38:10.296708", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.296809", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.296903", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-07-11T07:38:10.297013", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.297103", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-07-11T07:38:10.297670", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-07-11T07:38:10.297794", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.297913", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.298726", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.298841", "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:38:10.299070", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.300123", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.300245", "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:38:10.300480", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.301496", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/0/tests/0_commands test_uuid 1_1.1.3.1"}
- {"dt": "2024-07-11T07:38:10.301920", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.302040", "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-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/0/tests/0_commands/run.sh"}}}
- {"dt": "2024-07-11T07:38:10.302243", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-07-11T07:38:10.302363", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/lava-overlay-ioi0b3g_/lava-1/0 for stage 0"}
- {"dt": "2024-07-11T07:38:10.302533", "lvl": "debug", "msg": "- 0_commands"}
- {"dt": "2024-07-11T07:38:10.302700", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.302828", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.314713", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.314926", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315060", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315187", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315310", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315407", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-07-11T07:38:10.315517", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315633", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315743", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315859", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.315975", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:10.316253", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2j3NWZxdeVbgIPF4MgXdEwDRflD/Image.gz"}
- {"dt": "2024-07-11T07:38:10.316369", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/kernel/Image"}
- {"dt": "2024-07-11T07:38:10.316462", "lvl": "debug", "msg": "total size: 16403416 (15\u202FMB)"}
- {"dt": "2024-07-11T07:38:10.316554", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-07-11T07:38:10.968321", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.400362", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.508289", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.578474", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.648376", "lvl": "debug", "msg": "progress  20\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.718617", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.788550", "lvl": "debug", "msg": "progress  30\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.858555", "lvl": "debug", "msg": "progress  35\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.928725", "lvl": "debug", "msg": "progress  40\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T07:38:11.998542", "lvl": "debug", "msg": "progress  45\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.068576", "lvl": "debug", "msg": "progress  50\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.138533", "lvl": "debug", "msg": "progress  55\u202F% (8\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.208404", "lvl": "debug", "msg": "progress  60\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.278412", "lvl": "debug", "msg": "progress  65\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.348239", "lvl": "debug", "msg": "progress  70\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.418261", "lvl": "debug", "msg": "progress  75\u202F% (11\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.488185", "lvl": "debug", "msg": "progress  80\u202F% (12\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.557920", "lvl": "debug", "msg": "progress  85\u202F% (13\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.764862", "lvl": "debug", "msg": "progress  90\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.796740", "lvl": "debug", "msg": "progress  95\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.867044", "lvl": "debug", "msg": "progress 100\u202F% (15\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.873781", "lvl": "info", "msg": "15\u202FMB downloaded in 2.56\u202Fs (6.12\u202FMB/s)"}
- {"dt": "2024-07-11T07:38:12.873974", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:38:12.874047", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.4.1", "duration": "2.56", "result": "pass", "extra": {"label": "kernel", "size": ! "16403416", "md5sum": "8f490c95e40b5e3aa1b0033877508a3b", "sha256sum": "8da1b750e5fad76513a8976382ea3696aaf65d256877df963eb72f0b7acf763f", "sha512sum": "072cbb2f89dc99275873528e42012bc2895cdce0860e75d81b23b2a615617dd6df0127a98b4a0e2948790421246c1a5c3025bc28e3c5aa528a22f8ced1578b0e"}}}
- {"dt": "2024-07-11T07:38:12.874194", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:38:12.874286", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:57) [common]"}
- {"dt": "2024-07-11T07:38:12.874365", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:57) [common]"}
- {"dt": "2024-07-11T07:38:12.874554", "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:38:12.874622", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4"}
- {"dt": "2024-07-11T07:38:12.874677", "lvl": "debug", "msg": "total size: 192741048 (183\u202FMB)"}
- {"dt": "2024-07-11T07:38:12.874732", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:38:12.875100", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:13.248279", "lvl": "debug", "msg": "progress   5\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:38:13.561499", "lvl": "debug", "msg": "progress  10\u202F% (18\u202FMB)"}
- {"dt": "2024-07-11T07:38:13.885200", "lvl": "debug", "msg": "progress  15\u202F% (27\u202FMB)"}
- {"dt": "2024-07-11T07:38:14.205369", "lvl": "debug", "msg": "progress  20\u202F% (36\u202FMB)"}
- {"dt": "2024-07-11T07:38:14.536208", "lvl": "debug", "msg": "progress  25\u202F% (45\u202FMB)"}
- {"dt": "2024-07-11T07:38:14.855144", "lvl": "debug", "msg": "progress  30\u202F% (55\u202FMB)"}
- {"dt": "2024-07-11T07:38:15.172265", "lvl": "debug", "msg": "progress  35\u202F% (64\u202FMB)"}
- {"dt": "2024-07-11T07:38:15.493912", "lvl": "debug", "msg": "progress  40\u202F% (73\u202FMB)"}
- {"dt": "2024-07-11T07:38:15.809349", "lvl": "debug", "msg": "progress  45\u202F% (82\u202FMB)"}
- {"dt": "2024-07-11T07:38:16.133550", "lvl": "debug", "msg": "progress  50\u202F% (91\u202FMB)"}
- {"dt": "2024-07-11T07:38:16.460995", "lvl": "debug", "msg": "progress  55\u202F% (101\u202FMB)"}
- {"dt": "2024-07-11T07:38:16.773673", "lvl": "debug", "msg": "progress  60\u202F% (110\u202FMB)"}
- {"dt": "2024-07-11T07:38:17.114476", "lvl": "debug", "msg": "progress  65\u202F% (119\u202FMB)"}
- {"dt": "2024-07-11T07:38:17.444703", "lvl": "debug", "msg": "progress  70\u202F% (128\u202FMB)"}
- {"dt": "2024-07-11T07:38:17.746065", "lvl": "debug", "msg": "progress  75\u202F% (137\u202FMB)"}
- {"dt": "2024-07-11T07:38:18.072385", "lvl": "debug", "msg": "progress  80\u202F% (147\u202FMB)"}
- {"dt": "2024-07-11T07:38:18.378458", "lvl": "debug", "msg": "progress  85\u202F% (156\u202FMB)"}
- {"dt": "2024-07-11T07:38:18.682644", "lvl": "debug", "msg": "progress  90\u202F% (165\u202FMB)"}
- {"dt": "2024-07-11T07:38:18.950951", "lvl": "debug", "msg": "progress  95\u202F% (174\u202FMB)"}
- {"dt": "2024-07-11T07:38:20.625827", "lvl": "debug", "msg": "progress 100\u202F% (183\u202FMB)"}
- {"dt": "2024-07-11T07:38:21.824979", "lvl": "info", "msg": "183\u202FMB downloaded in 8.95\u202Fs (20.54\u202FMB/s)"}
- {"dt": "2024-07-11T07:38:21.825221", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:09) [common]"}
- {"dt": "2024-07-11T07:38:21.825284", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "8.95", "result": "pass", "extra": {"label": "rootfs", "size": ! "192741048", "md5sum": "6eee656eff611edb504603c92d50df3e", "sha256sum": "f3592bf199e57df8c9160949e830ea3415acbf526acf5579cf5c75ff89618917", "sha512sum": "c75faf3e3c5f36b15dca12e1ff7a8fbe4ed2f9234bcb73f5f8b7724d970b8c33fb6e06b08dedfc9f0c1264e9f4415c94c7e5e785ac7154b40a8c21a719d641b3"}}}
- {"dt": "2024-07-11T07:38:21.825414", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:48) [common]"}
- {"dt": "2024-07-11T07:38:21.825478", "lvl": "debug", "msg": "start: 1.5.2.1 http-download (timeout 00:04:48) [common]"}
- {"dt": "2024-07-11T07:38:21.825672", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2j3NWZxdeVbgIPF4MgXdEwDRflD/modules.tar.xz"}
- {"dt": "2024-07-11T07:38:21.825728", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.modules/modules.tar"}
- {"dt": "2024-07-11T07:38:21.825774", "lvl": "debug", "msg": "total size: 18405980 (17\u202FMB)"}
- {"dt": "2024-07-11T07:38:21.825818", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:38:22.460934", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:22.892100", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.008098", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.085733", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.164404", "lvl": "debug", "msg": "progress  20\u202F% (3\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.242395", "lvl": "debug", "msg": "progress  25\u202F% (4\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.320999", "lvl": "debug", "msg": "progress  30\u202F% (5\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.399198", "lvl": "debug", "msg": "progress  35\u202F% (6\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.483632", "lvl": "debug", "msg": "progress  40\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.556238", "lvl": "debug", "msg": "progress  45\u202F% (7\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.635072", "lvl": "debug", "msg": "progress  50\u202F% (8\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.713668", "lvl": "debug", "msg": "progress  55\u202F% (9\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.794966", "lvl": "debug", "msg": "progress  60\u202F% (10\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.905010", "lvl": "debug", "msg": "progress  65\u202F% (11\u202FMB)"}
- {"dt": "2024-07-11T07:38:23.951815", "lvl": "debug", "msg": "progress  70\u202F% (12\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.030208", "lvl": "debug", "msg": "progress  75\u202F% (13\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.370417", "lvl": "debug", "msg": "progress  80\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.400255", "lvl": "debug", "msg": "progress  85\u202F% (14\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.429662", "lvl": "debug", "msg": "progress  90\u202F% (15\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.463099", "lvl": "debug", "msg": "progress  95\u202F% (16\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.523893", "lvl": "debug", "msg": "progress 100\u202F% (17\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.526805", "lvl": "info", "msg": "17\u202FMB downloaded in 2.70\u202Fs (6.50\u202FMB/s)"}
- {"dt": "2024-07-11T07:38:24.527094", "lvl": "debug", "msg": "end: 1.5.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:38:24.527277", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.2.1", "duration": "2.70", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "18405980", "md5sum": "c53c40c0363f7074005a9a93fc8b40d0", "sha256sum": "c7087b152b162f2ec27fe311e6324c2a741143b3e9b0cc4d89ff6fc48cd4ae88", "sha512sum": "ac3b9cb2600178c85d84f08a7ac702f50593250abca796fbbaf7183bd4317629981b7ff2cbba61820aaabd01d2f4741e711fc3e571453c83c94e8d7356874148"}}}
- {"dt": "2024-07-11T07:38:24.527656", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:38:24.527865", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:04:46) [common]"}
- {"dt": "2024-07-11T07:38:24.528054", "lvl": "debug", "msg": "start: 1.5.3.1 http-download (timeout 00:04:46) [common]"}
- {"dt": "2024-07-11T07:38:24.528450", "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:38:24.528630", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.overlay-00/kvm-unit-tests.tar"}
- {"dt": "2024-07-11T07:38:24.528792", "lvl": "debug", "msg": "total size: 1364352 (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:24.528954", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-07-11T07:38:25.474130", "lvl": "debug", "msg": "progress   2\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.583464", "lvl": "debug", "msg": "progress   7\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.589075", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.697429", "lvl": "debug", "msg": "progress  19\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.702334", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.801687", "lvl": "debug", "msg": "progress  31\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.807799", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.815736", "lvl": "debug", "msg": "progress  43\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.821780", "lvl": "debug", "msg": "progress  48\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.830357", "lvl": "debug", "msg": "progress  55\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.908894", "lvl": "debug", "msg": "progress  60\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.917606", "lvl": "debug", "msg": "progress  67\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.923242", "lvl": "debug", "msg": "progress  72\u202F% (0\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.931275", "lvl": "debug", "msg": "progress  79\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.937324", "lvl": "debug", "msg": "progress  84\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.944684", "lvl": "debug", "msg": "progress  91\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.950161", "lvl": "debug", "msg": "progress  96\u202F% (1\u202FMB)"}
- {"dt": "2024-07-11T07:38:25.955858", "lvl": "info", "msg": "1\u202FMB downloaded in 1.43\u202Fs (0.91\u202FMB/s)"}
- {"dt": "2024-07-11T07:38:25.956020", "lvl": "debug", "msg": "end: 1.5.3.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:25.956080", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.3.1", "duration": "1.43", "result": "pass", "extra": {"label": "rootfs.overlay-00", "size": ! "1364352", "md5sum": "0998f24ccef636eccd7561bbe058c5a8", "sha256sum": "74b923dc8c71d86e7da540929c04ae135b922df79251dc513072d0762a9592f3", "sha512sum": "e6f7c545d9ac0a5c57df9c60233442af001bea79eb28dcac07fafaf9cde0885489514924454e0306a3cb17b1a02d33481bd29bbc8f08d9804131d7607b415d63"}}}
- {"dt": "2024-07-11T07:38:25.956196", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:25.956258", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:04:44) [common]"}
- {"dt": "2024-07-11T07:38:25.956309", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs/https___storage.tuxboot.com_debian-kvm_bookworm_arm64_rootfs.ext4'"}
- {"dt": "2024-07-11T07:38:27.775022", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-07-11T07:38:27.776210", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/compress-overlay-1eir34kw/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-07-11T07:38:27.782201", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.modules/modules.tar' to '/'"}
- {"dt": "2024-07-11T07:38:28.117404", "lvl": "debug", "msg": "- rootfs.overlay-00: '/var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.overlay-00/kvm-unit-tests.tar' to '/'"}
- {"dt": "2024-07-11T07:38:28.466597", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2024-07-11T07:38:28.467206", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:16) [common]"}
- {"dt": "2024-07-11T07:38:28.467391", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:18) [common]"}
- {"dt": "2024-07-11T07:38:28.467580", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:30:00) [common]"}
- {"dt": "2024-07-11T07:38:28.467751", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:30:00) [common]"}
- {"dt": "2024-07-11T07:38:28.467908", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:30:00) [common]"}
- {"dt": "2024-07-11T07:38:28.468449", "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-128l9yvw-1/deployimages-7xd54dy4/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/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:38:28.472842", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-07-11T07:38:28.473468", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:28.473629", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.01", "result": "pass", "extra": {"qemu_version": "1:8.2.4+ds-1", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2024-07-11T07:38:28.473935", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:28.474110", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:30:00) [common]"}
- {"dt": "2024-07-11T07:38:28.474302", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-07-11T07:38:28.474445", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2024-07-11T07:38:28.616275", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-07-11T07:38:28.616622", "lvl": "target", "msg": "[    0.000000] Linux version 6.10.0-rc7-next-20240710 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP @1720612166"}
- {"dt": "2024-07-11T07:38:28.616754", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-07-11T07:38:28.616878", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-07-11T07:38:28.616997", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-07-11T07:38:28.617078", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-07-11T07:38:28.617191", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-07-11T07:38:28.617294", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-07-11T07:38:28.617644", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:30:00) [common]"}
- {"dt": "2024-07-11T07:38:28.617765", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-07-11T07:38:28.617882", "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:38:28.617993", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-07-11T07:38:28.618078", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-07-11T07:38:28.618171", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-07-11T07:38:28.618253", "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:38:28.618505", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:30:00)"}
- {"dt": "2024-07-11T07:38:28.618604", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2024-07-11T07:38:28.620709", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-07-11T07:38:28.620983", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:28.621499", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f5fb240-0x13f5fdfff]"}
- {"dt": "2024-07-11T07:38:28.623367", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-07-11T07:38:28.623779", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-07-11T07:38:28.623880", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-07-11T07:38:28.624060", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:28.624182", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-07-11T07:38:28.624309", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-07-11T07:38:28.624506", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:28.624882", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:28.648823", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fc000000 on node -1"}
- {"dt": "2024-07-11T07:38:28.649675", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-07-11T07:38:28.649853", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-07-11T07:38:28.650008", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-07-11T07:38:28.650248", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-07-11T07:38:28.650402", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-07-11T07:38:28.652505", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 34 pages/cpu s100440 r8192 d30632 u139264"}
- {"dt": "2024-07-11T07:38:28.653055", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s100440 r8192 d30632 u139264 alloc=34*4096"}
- {"dt": "2024-07-11T07:38:28.653309", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-07-11T07:38:28.654504", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-07-11T07:38:28.656211", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:38:28.656488", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-07-11T07:38:28.656709", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-07-11T07:38:28.656982", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-07-11T07:38:28.657248", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-07-11T07:38:28.657466", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-07-11T07:38:28.657785", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-07-11T07:38:28.661194", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-07-11T07:38:28.663918", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2024-07-11T07:38:28.666342", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-07-11T07:38:28.670735", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-07-11T07:38:28.671924", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-07-11T07:38:28.673424", "lvl": "target", "msg": "<6>[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-07-11T07:38:28.673522", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-07-11T07:38:28.673704", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2024-07-11T07:38:28.675047", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off"}
- {"dt": "2024-07-11T07:38:28.675326", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-07-11T07:38:28.725749", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000f8000000-0x00000000fc000000] (64MB)"}
- {"dt": "2024-07-11T07:38:28.749190", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-07-11T07:38:28.750782", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocating 65320 entries in 256 pages"}
- {"dt": "2024-07-11T07:38:29.127833", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocated 256 pages with 1 groups"}
- {"dt": "2024-07-11T07:38:29.130486", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-07-11T07:38:29.137028", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-07-11T07:38:29.137167", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2024-07-11T07:38:29.137463", "lvl": "target", "msg": "<6>[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:38:29.137546", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:38:29.137871", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-07-11T07:38:29.138094", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-07-11T07:38:29.139607", "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:38:29.139784", "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:38:29.158773", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-07-11T07:38:29.164404", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-07-11T07:38:29.164566", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-07-11T07:38:29.164643", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-07-11T07:38:29.166066", "lvl": "target", "msg": "<6>[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-07-11T07:38:29.166233", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-07-11T07:38:29.166459", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-07-11T07:38:29.167123", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-07-11T07:38:29.171935", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-07-11T07:38:29.172882", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100210000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:38:29.173259", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100220000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:38:29.174048", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000100230000"}
- {"dt": "2024-07-11T07:38:29.174838", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100240000"}
- {"dt": "2024-07-11T07:38:29.176908", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-07-11T07:38:29.182995", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-07-11T07:38:29.183445", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-07-11T07:38:29.184087", "lvl": "target", "msg": "<6>[    0.000060] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-07-11T07:38:29.187089", "lvl": "target", "msg": "<6>[    0.002973] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)"}
- {"dt": "2024-07-11T07:38:29.200499", "lvl": "target", "msg": "<6>[    0.014460] Console: colour dummy device 80x25"}
- {"dt": "2024-07-11T07:38:29.206783", "lvl": "target", "msg": "<6>[    0.022302] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-07-11T07:38:29.206972", "lvl": "target", "msg": "<6>[    0.023139] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-07-11T07:38:29.211188", "lvl": "target", "msg": "<6>[    0.026927] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,tomoyo,bpf,ima,evm"}
- {"dt": "2024-07-11T07:38:29.212193", "lvl": "target", "msg": "<6>[    0.028228] landlock: Up and running."}
- {"dt": "2024-07-11T07:38:29.212278", "lvl": "target", "msg": "<6>[    0.028376] Yama: becoming mindful."}
- {"dt": "2024-07-11T07:38:29.215265", "lvl": "target", "msg": "<6>[    0.031285] AppArmor: AppArmor initialized"}
- {"dt": "2024-07-11T07:38:29.215377", "lvl": "target", "msg": "<6>[    0.031497] TOMOYO Linux initialized"}
- {"dt": "2024-07-11T07:38:29.216199", "lvl": "target", "msg": "<6>[    0.032229] LSM support for eBPF active"}
- {"dt": "2024-07-11T07:38:29.219731", "lvl": "target", "msg": "<6>[    0.035728] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.220231", "lvl": "target", "msg": "<6>[    0.036052] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.252275", "lvl": "target", "msg": "<4>[    0.068178] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-07-11T07:38:29.265722", "lvl": "target", "msg": "<6>[    0.081666] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-07-11T07:38:29.265912", "lvl": "target", "msg": "<6>[    0.081893] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-07-11T07:38:29.275880", "lvl": "target", "msg": "<6>[    0.091854] Platform MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:29.276619", "lvl": "target", "msg": "<6>[    0.092486] PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:29.276965", "lvl": "target", "msg": "<6>[    0.093126] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:29.280073", "lvl": "target", "msg": "<6>[    0.096092] EFI services will not be available."}
- {"dt": "2024-07-11T07:38:29.282250", "lvl": "target", "msg": "<6>[    0.098276] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-07-11T07:38:29.308673", "lvl": "target", "msg": "<6>[    0.106714] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-07-11T07:38:29.308843", "lvl": "target", "msg": "<6>[    0.110750] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-07-11T07:38:29.308921", "lvl": "target", "msg": "<6>[    0.112525] GICv3: CPU1: using allocated LPI pending table @0x0000000100250000"}
- {"dt": "2024-07-11T07:38:29.309031", "lvl": "target", "msg": "<6>[    0.116621] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-07-11T07:38:29.309155", "lvl": "target", "msg": "<6>[    0.124586] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-07-11T07:38:29.309338", "lvl": "target", "msg": "<6>[    0.125504] SMP: Total of 2 processors activated."}
- {"dt": "2024-07-11T07:38:29.309505", "lvl": "target", "msg": "<6>[    0.125689] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-07-11T07:38:29.309815", "lvl": "target", "msg": "<6>[    0.125899] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-07-11T07:38:29.309983", "lvl": "target", "msg": "<6>[    0.126141] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-07-11T07:38:29.310182", "lvl": "target", "msg": "<6>[    0.126325] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-07-11T07:38:29.310391", "lvl": "target", "msg": "<6>[    0.126505] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-07-11T07:38:29.310685", "lvl": "target", "msg": "<6>[    0.126745] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-07-11T07:38:29.310942", "lvl": "target", "msg": "<6>[    0.127059] CPU features: detected: Common not Private translations"}
- {"dt": "2024-07-11T07:38:29.311105", "lvl": "target", "msg": "<6>[    0.127267] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-07-11T07:38:29.311474", "lvl": "target", "msg": "<6>[    0.127435] CPU features: detected: Data cache clean to Point of Deep Persistence"}
- {"dt": "2024-07-11T07:38:29.311878", "lvl": "target", "msg": "<6>[    0.127749] CPU features: detected: Data cache clean to Point of Persistence"}
- {"dt": "2024-07-11T07:38:29.312019", "lvl": "target", "msg": "<6>[    0.128118] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-07-11T07:38:29.312166", "lvl": "target", "msg": "<6>[    0.128346] CPU features: detected: E0PD"}
- {"dt": "2024-07-11T07:38:29.312374", "lvl": "target", "msg": "<6>[    0.128491] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-07-11T07:38:29.312573", "lvl": "target", "msg": "<6>[    0.128702] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-07-11T07:38:29.312745", "lvl": "target", "msg": "<6>[    0.128902] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-07-11T07:38:29.313080", "lvl": "target", "msg": "<6>[    0.129138] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:38:29.313317", "lvl": "target", "msg": "<6>[    0.129453] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-07-11T07:38:29.313581", "lvl": "target", "msg": "<6>[    0.129685] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-07-11T07:38:29.313772", "lvl": "target", "msg": "<6>[    0.129915] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-07-11T07:38:29.313987", "lvl": "target", "msg": "<6>[    0.130097] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2024-07-11T07:38:29.314186", "lvl": "target", "msg": "<6>[    0.130314] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-07-11T07:38:29.314374", "lvl": "target", "msg": "<6>[    0.130508] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-07-11T07:38:29.314570", "lvl": "target", "msg": "<6>[    0.130700] CPU features: detected: Random Number Generator"}
- {"dt": "2024-07-11T07:38:29.314749", "lvl": "target", "msg": "<6>[    0.130891] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-07-11T07:38:29.314939", "lvl": "target", "msg": "<6>[    0.131077] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-07-11T07:38:29.315183", "lvl": "target", "msg": "<6>[    0.131263] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2024-07-11T07:38:29.315394", "lvl": "target", "msg": "<6>[    0.131513] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-07-11T07:38:29.315780", "lvl": "target", "msg": "<6>[    0.131753] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-07-11T07:38:29.315876", "lvl": "target", "msg": "<6>[    0.132011] CPU features: detected: FA64"}
- {"dt": "2024-07-11T07:38:29.316109", "lvl": "target", "msg": "<6>[    0.132214] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-07-11T07:38:29.316302", "lvl": "target", "msg": "<6>[    0.132437] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-07-11T07:38:29.335384", "lvl": "target", "msg": "<6>[    0.148645] alternatives: applying system-wide alternatives"}
- {"dt": "2024-07-11T07:38:29.351029", "lvl": "target", "msg": "<6>[    0.166478] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-07-11T07:38:29.351275", "lvl": "target", "msg": "<6>[    0.167382] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:38:29.351473", "lvl": "target", "msg": "<6>[    0.167607] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-07-11T07:38:29.353047", "lvl": "target", "msg": "<6>[    0.169072] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-07-11T07:38:29.353169", "lvl": "target", "msg": "<6>[    0.169290] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:38:29.353359", "lvl": "target", "msg": "<6>[    0.169496] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-07-11T07:38:29.394241", "lvl": "target", "msg": "<6>[    0.210120] node 0 deferred pages initialised in 8ms"}
- {"dt": "2024-07-11T07:38:29.396791", "lvl": "target", "msg": "<6>[    0.211861] Memory: 3920168K/4194304K available (20096K kernel code, 6228K rwdata, 12880K rodata, 14016K init, 982K bss, 204312K reserved, 65536K cma-reserved)"}
- {"dt": "2024-07-11T07:38:29.428473", "lvl": "target", "msg": "<6>[    0.244370] devtmpfs: initialized"}
- {"dt": "2024-07-11T07:38:29.459496", "lvl": "target", "msg": "<6>[    0.275050] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-07-11T07:38:29.460739", "lvl": "target", "msg": "<6>[    0.276532] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.466305", "lvl": "target", "msg": "<6>[    0.282044] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-07-11T07:38:29.466909", "lvl": "target", "msg": "<6>[    0.282730] 0 pages in range for non-PLT usage"}
- {"dt": "2024-07-11T07:38:29.474991", "lvl": "target", "msg": "<6>[    0.282887] 510672 pages in range for PLT usage"}
- {"dt": "2024-07-11T07:38:29.475351", "lvl": "target", "msg": "<6>[    0.290970] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-07-11T07:38:29.490182", "lvl": "target", "msg": "<6>[    0.306090] DMI not present or invalid."}
- {"dt": "2024-07-11T07:38:29.521675", "lvl": "target", "msg": "<6>[    0.337551] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-07-11T07:38:29.535310", "lvl": "target", "msg": "<6>[    0.351164] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:29.536319", "lvl": "target", "msg": "<6>[    0.352324] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:29.538125", "lvl": "target", "msg": "<6>[    0.354145] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:29.538816", "lvl": "target", "msg": "<6>[    0.354864] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-07-11T07:38:29.542116", "lvl": "target", "msg": "<5>[    0.358126] audit: type=2000 audit(0.324:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-07-11T07:38:29.553297", "lvl": "target", "msg": "<6>[    0.369136] thermal_sys: Registered thermal governor 'fair_share'"}
- {"dt": "2024-07-11T07:38:29.553780", "lvl": "target", "msg": "<6>[    0.369237] thermal_sys: Registered thermal governor 'bang_bang'"}
- {"dt": "2024-07-11T07:38:29.554323", "lvl": "target", "msg": "<6>[    0.369643] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-07-11T07:38:29.554839", "lvl": "target", "msg": "<6>[    0.370216] thermal_sys: Registered thermal governor 'user_space'"}
- {"dt": "2024-07-11T07:38:29.556396", "lvl": "target", "msg": "<6>[    0.370666] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-07-11T07:38:29.556652", "lvl": "target", "msg": "<6>[    0.372312] cpuidle: using governor ladder"}
- {"dt": "2024-07-11T07:38:29.557500", "lvl": "target", "msg": "<6>[    0.373504] cpuidle: using governor menu"}
- {"dt": "2024-07-11T07:38:29.561691", "lvl": "target", "msg": "<6>[    0.377572] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-07-11T07:38:29.563075", "lvl": "target", "msg": "<6>[    0.379011] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-07-11T07:38:29.579433", "lvl": "target", "msg": "<6>[    0.395385] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-07-11T07:38:29.679408", "lvl": "target", "msg": "<6>[    0.495309] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-07-11T07:38:29.681260", "lvl": "target", "msg": "<6>[    0.497154] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:38:29.681368", "lvl": "target", "msg": "<6>[    0.497154] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:38:29.681610", "lvl": "target", "msg": "<6>[    0.497743] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:38:29.681768", "lvl": "target", "msg": "<6>[    0.497743] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:38:29.711168", "lvl": "target", "msg": "<6>[    0.527081] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:29.711356", "lvl": "target", "msg": "<6>[    0.527342] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-07-11T07:38:29.711447", "lvl": "target", "msg": "<6>[    0.527547] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:29.711627", "lvl": "target", "msg": "<6>[    0.527754] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-07-11T07:38:29.711840", "lvl": "target", "msg": "<6>[    0.527959] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:29.712290", "lvl": "target", "msg": "<6>[    0.528168] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-07-11T07:38:29.712383", "lvl": "target", "msg": "<6>[    0.528497] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:29.712616", "lvl": "target", "msg": "<6>[    0.528710] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-07-11T07:38:29.722210", "lvl": "target", "msg": "<6>[    0.538132] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-07-11T07:38:29.732035", "lvl": "target", "msg": "<6>[    0.547994] ACPI: Interpreter disabled."}
- {"dt": "2024-07-11T07:38:29.745906", "lvl": "target", "msg": "<6>[    0.561806] iommu: Default domain type: Translated"}
- {"dt": "2024-07-11T07:38:29.746099", "lvl": "target", "msg": "<6>[    0.562078] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-07-11T07:38:29.749390", "lvl": "target", "msg": "<5>[    0.565392] SCSI subsystem initialized"}
- {"dt": "2024-07-11T07:38:29.753866", "lvl": "target", "msg": "<6>[    0.569866] mc: Linux media interface: v0.10"}
- {"dt": "2024-07-11T07:38:29.754115", "lvl": "target", "msg": "<6>[    0.570252] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-07-11T07:38:29.754723", "lvl": "target", "msg": "<6>[    0.570748] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-07-11T07:38:29.754845", "lvl": "target", "msg": "<6>[    0.570922] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-07-11T07:38:29.755182", "lvl": "target", "msg": "<6>[    0.571258] PTP clock support registered"}
- {"dt": "2024-07-11T07:38:29.756225", "lvl": "target", "msg": "<6>[    0.572244] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-07-11T07:38:29.758152", "lvl": "target", "msg": "<6>[    0.574163] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-07-11T07:38:29.762976", "lvl": "target", "msg": "<6>[    0.578982] FPGA manager framework"}
- {"dt": "2024-07-11T07:38:29.763666", "lvl": "target", "msg": "<6>[    0.579702] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-07-11T07:38:29.772906", "lvl": "target", "msg": "<6>[    0.588865] NetLabel: Initializing"}
- {"dt": "2024-07-11T07:38:29.773055", "lvl": "target", "msg": "<6>[    0.589029] NetLabel:  domain hash size = 128"}
- {"dt": "2024-07-11T07:38:29.773156", "lvl": "target", "msg": "<6>[    0.589182] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-07-11T07:38:29.774337", "lvl": "target", "msg": "<6>[    0.590352] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-07-11T07:38:29.776081", "lvl": "target", "msg": "<6>[    0.592090] vgaarb: loaded"}
- {"dt": "2024-07-11T07:38:29.780083", "lvl": "target", "msg": "<6>[    0.596040] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-07-11T07:38:29.787029", "lvl": "target", "msg": "<5>[    0.602914] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-07-11T07:38:29.787219", "lvl": "target", "msg": "<6>[    0.603329] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-07-11T07:38:29.789774", "lvl": "target", "msg": "<5>[    0.605732] netfs: FS-Cache loaded"}
- {"dt": "2024-07-11T07:38:29.793947", "lvl": "target", "msg": "<6>[    0.609952] AppArmor: AppArmor Filesystem Enabled"}
- {"dt": "2024-07-11T07:38:29.794324", "lvl": "target", "msg": "<6>[    0.610484] pnp: PnP ACPI: disabled"}
- {"dt": "2024-07-11T07:38:29.832079", "lvl": "target", "msg": "<6>[    0.647997] NET: Registered PF_INET protocol family"}
- {"dt": "2024-07-11T07:38:29.833527", "lvl": "target", "msg": "<6>[    0.649550] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.842365", "lvl": "target", "msg": "<6>[    0.658339] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.842880", "lvl": "target", "msg": "<6>[    0.658916] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.843290", "lvl": "target", "msg": "<6>[    0.659352] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.844443", "lvl": "target", "msg": "<6>[    0.660466] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.845170", "lvl": "target", "msg": "<6>[    0.661197] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-07-11T07:38:29.847016", "lvl": "target", "msg": "<6>[    0.663046] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.847623", "lvl": "target", "msg": "<6>[    0.663646] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.848219", "lvl": "target", "msg": "<6>[    0.664241] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:29.849818", "lvl": "target", "msg": "<6>[    0.665853] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-07-11T07:38:29.850463", "lvl": "target", "msg": "<6>[    0.666490] NET: Registered PF_XDP protocol family"}
- {"dt": "2024-07-11T07:38:29.850625", "lvl": "target", "msg": "<6>[    0.666812] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-07-11T07:38:29.865214", "lvl": "target", "msg": "<6>[    0.681162] kvm [1]: nv: 529 coarse grained trap handlers"}
- {"dt": "2024-07-11T07:38:29.866202", "lvl": "target", "msg": "<6>[    0.682238] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2024-07-11T07:38:29.867249", "lvl": "target", "msg": "<6>[    0.683283] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-07-11T07:38:29.868088", "lvl": "target", "msg": "<6>[    0.684124] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-07-11T07:38:29.868201", "lvl": "target", "msg": "<6>[    0.684384] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-07-11T07:38:29.868643", "lvl": "target", "msg": "<6>[    0.684757] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-07-11T07:38:29.869808", "lvl": "target", "msg": "<6>[    0.685843] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-07-11T07:38:29.870481", "lvl": "target", "msg": "<6>[    0.686510] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-07-11T07:38:29.876543", "lvl": "target", "msg": "<5>[    0.692543] Initialise system trusted keyrings"}
- {"dt": "2024-07-11T07:38:29.878022", "lvl": "target", "msg": "<5>[    0.694068] Key type blacklist registered"}
- {"dt": "2024-07-11T07:38:29.880311", "lvl": "target", "msg": "<6>[    0.696165] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-07-11T07:38:29.892167", "lvl": "target", "msg": "<6>[    0.708071] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-07-11T07:38:29.896214", "lvl": "target", "msg": "<5>[    0.712187] integrity: Platform Keyring initialized"}
- {"dt": "2024-07-11T07:38:29.896879", "lvl": "target", "msg": "<5>[    0.712901] integrity: Machine keyring initialized"}
- {"dt": "2024-07-11T07:38:29.971848", "lvl": "target", "msg": "<6>[    0.787754] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-07-11T07:38:29.972057", "lvl": "target", "msg": "<5>[    0.788166] Key type asymmetric registered"}
- {"dt": "2024-07-11T07:38:29.972287", "lvl": "target", "msg": "<5>[    0.788406] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-07-11T07:38:29.973037", "lvl": "target", "msg": "<6>[    0.789062] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)"}
- {"dt": "2024-07-11T07:38:29.974940", "lvl": "target", "msg": "<6>[    0.790932] io scheduler mq-deadline registered"}
- {"dt": "2024-07-11T07:38:30.025950", "lvl": "target", "msg": "<6>[    0.841799] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-07-11T07:38:30.037125", "lvl": "target", "msg": "<6>[    0.853009] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-07-11T07:38:30.037915", "lvl": "target", "msg": "<6>[    0.853861] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-07-11T07:38:30.038574", "lvl": "target", "msg": "<6>[    0.854609] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-07-11T07:38:30.038932", "lvl": "target", "msg": "<6>[    0.854954] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-07-11T07:38:30.039810", "lvl": "target", "msg": "<4>[    0.855838] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-07-11T07:38:30.040807", "lvl": "target", "msg": "<6>[    0.856650] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-07-11T07:38:30.042178", "lvl": "target", "msg": "<6>[    0.858199] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-07-11T07:38:30.042568", "lvl": "target", "msg": "<6>[    0.858705] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-07-11T07:38:30.042897", "lvl": "target", "msg": "<6>[    0.858948] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:38:30.043060", "lvl": "target", "msg": "<6>[    0.859179] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:38:30.043299", "lvl": "target", "msg": "<6>[    0.859396] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:38:30.045062", "lvl": "target", "msg": "<6>[    0.861077] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-07-11T07:38:30.050087", "lvl": "target", "msg": "<6>[    0.866106] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:38:30.050234", "lvl": "target", "msg": "<6>[    0.866333] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:38:30.050477", "lvl": "target", "msg": "<6>[    0.866570] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:38:30.218257", "lvl": "target", "msg": "<6>[    1.034166] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-07-11T07:38:30.231543", "lvl": "target", "msg": "<6>[    1.047501] Serial: AMBA driver"}
- {"dt": "2024-07-11T07:38:30.235156", "lvl": "target", "msg": "<6>[    1.051158] msm_serial: driver initialized"}
- {"dt": "2024-07-11T07:38:30.236923", "lvl": "target", "msg": "<6>[    1.052951] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-07-11T07:38:30.237752", "lvl": "target", "msg": "<6>[    1.053776] STM32 USART driver initialized"}
- {"dt": "2024-07-11T07:38:30.249407", "lvl": "target", "msg": "<6>[    1.065340] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-07-11T07:38:30.256769", "lvl": "target", "msg": "<5>[    1.072696] virtio_blk virtio0: [vda] 6059648 512-byte logical blocks (3.10 GB/2.89 GiB)"}
- {"dt": "2024-07-11T07:38:30.276495", "lvl": "target", "msg": "<6>[    1.092400] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-07-11T07:38:30.282239", "lvl": "target", "msg": "<5>[    1.098174] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-07-11T07:38:30.283893", "lvl": "target", "msg": "<6>[    1.099849] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:38:30.284533", "lvl": "target", "msg": "<6>[    1.100549] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:38:30.285345", "lvl": "target", "msg": "<6>[    1.101371] Using buffer write method"}
- {"dt": "2024-07-11T07:38:30.285714", "lvl": "target", "msg": "<7>[    1.101814] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:38:30.286263", "lvl": "target", "msg": "<5>[    1.102225] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-07-11T07:38:30.287083", "lvl": "target", "msg": "<6>[    1.103011] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:38:30.287345", "lvl": "target", "msg": "<6>[    1.103437] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:38:30.288155", "lvl": "target", "msg": "<6>[    1.104176] Using buffer write method"}
- {"dt": "2024-07-11T07:38:30.288333", "lvl": "target", "msg": "<7>[    1.104388] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:38:30.288659", "lvl": "target", "msg": "<5>[    1.104724] Concatenating MTD devices:"}
- {"dt": "2024-07-11T07:38:30.288751", "lvl": "target", "msg": "<5>[    1.104914] (0): \"0.flash\""}
- {"dt": "2024-07-11T07:38:30.288874", "lvl": "target", "msg": "<5>[    1.105063] (1): \"0.flash\""}
- {"dt": "2024-07-11T07:38:30.289036", "lvl": "target", "msg": "<5>[    1.105211] into device \"0.flash\""}
- {"dt": "2024-07-11T07:38:30.321112", "lvl": "target", "msg": "<6>[    1.136977] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:38:30.321306", "lvl": "target", "msg": "<6>[    1.137207] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-07-11T07:38:30.321401", "lvl": "target", "msg": "<6>[    1.137510] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:38:30.321617", "lvl": "target", "msg": "<6>[    1.137709] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-07-11T07:38:30.321939", "lvl": "target", "msg": "<6>[    1.138069] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-07-11T07:38:30.322186", "lvl": "target", "msg": "<6>[    1.138274] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-07-11T07:38:30.322566", "lvl": "target", "msg": "<6>[    1.138627] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-07-11T07:38:30.322833", "lvl": "target", "msg": "<6>[    1.138906] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-07-11T07:38:30.333044", "lvl": "target", "msg": "<6>[    1.149020] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-07-11T07:38:30.334042", "lvl": "target", "msg": "<6>[    1.149890] rtc-pl031 9010000.pl031: setting system clock to 2024-07-11T07:38:30 UTC (1720683510)"}
- {"dt": "2024-07-11T07:38:30.337519", "lvl": "target", "msg": "<6>[    1.153529] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-07-11T07:38:30.370544", "lvl": "target", "msg": "<6>[    1.186445] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-07-11T07:38:30.370775", "lvl": "target", "msg": "<6>[    1.186765] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-07-11T07:38:30.374043", "lvl": "target", "msg": "<6>[    1.190037] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-07-11T07:38:30.378458", "lvl": "target", "msg": "<6>[    1.194477] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-07-11T07:38:30.387588", "lvl": "target", "msg": "<6>[    1.203483] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-07-11T07:38:30.416742", "lvl": "target", "msg": "<6>[    1.232563] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2024-07-11T07:38:30.417916", "lvl": "target", "msg": "<6>[    1.233929] watchdog: Delayed init of the lockup detector failed: -19"}
- {"dt": "2024-07-11T07:38:30.418142", "lvl": "target", "msg": "<6>[    1.234239] watchdog: Hard watchdog permanently disabled"}
- {"dt": "2024-07-11T07:38:30.424020", "lvl": "target", "msg": "<6>[    1.240005]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2024-07-11T07:38:30.448138", "lvl": "target", "msg": "<6>[    1.264043] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-07-11T07:38:30.462394", "lvl": "target", "msg": "<6>[    1.278379] Segment Routing with IPv6"}
- {"dt": "2024-07-11T07:38:30.462807", "lvl": "target", "msg": "<6>[    1.278912] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-07-11T07:38:30.463491", "lvl": "target", "msg": "<6>[    1.279530] mip6: Mobile IPv6"}
- {"dt": "2024-07-11T07:38:30.463826", "lvl": "target", "msg": "<6>[    1.279983] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-07-11T07:38:30.465395", "lvl": "target", "msg": "<6>[    1.281391] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-07-11T07:38:30.466141", "lvl": "target", "msg": "<5>[    1.282156] Key type dns_resolver registered"}
- {"dt": "2024-07-11T07:38:30.510443", "lvl": "target", "msg": "<6>[    1.326371] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-07-11T07:38:30.511200", "lvl": "target", "msg": "<3>[    1.327243] Timer migration setup failed"}
- {"dt": "2024-07-11T07:38:30.511816", "lvl": "target", "msg": "<6>[    1.327862] registered taskstats version 1"}
- {"dt": "2024-07-11T07:38:30.513213", "lvl": "target", "msg": "<5>[    1.329259] Loading compiled-in X.509 certificates"}
- {"dt": "2024-07-11T07:38:30.522666", "lvl": "target", "msg": "<5>[    1.338606] Loaded X.509 cert 'Build time autogenerated kernel key: 169ffd8f52f31ca9323fea8abf532340901819c3'"}
- {"dt": "2024-07-11T07:38:30.549539", "lvl": "target", "msg": "<6>[    1.365468] Demotion targets for Node 0: null"}
- {"dt": "2024-07-11T07:38:30.551947", "lvl": "target", "msg": "<5>[    1.367934] Key type .fscrypt registered"}
- {"dt": "2024-07-11T07:38:30.552113", "lvl": "target", "msg": "<5>[    1.368194] Key type fscrypt-provisioning registered"}
- {"dt": "2024-07-11T07:38:30.554618", "lvl": "target", "msg": "<5>[    1.370636] Key type encrypted registered"}
- {"dt": "2024-07-11T07:38:30.554899", "lvl": "target", "msg": "<6>[    1.370943] AppArmor: AppArmor sha256 policy hashing enabled"}
- {"dt": "2024-07-11T07:38:30.556174", "lvl": "target", "msg": "<6>[    1.372186] ima: No TPM chip found, activating TPM-bypass!"}
- {"dt": "2024-07-11T07:38:30.556429", "lvl": "target", "msg": "<6>[    1.372532] ima: Allocated hash algorithm: sha256"}
- {"dt": "2024-07-11T07:38:30.559672", "lvl": "target", "msg": "<6>[    1.375635] ima: No architecture policies found"}
- {"dt": "2024-07-11T07:38:30.561224", "lvl": "target", "msg": "<6>[    1.377243] evm: Initialising EVM extended attributes:"}
- {"dt": "2024-07-11T07:38:30.561376", "lvl": "target", "msg": "<6>[    1.377518] evm: security.selinux"}
- {"dt": "2024-07-11T07:38:30.561691", "lvl": "target", "msg": "<6>[    1.377741] evm: security.SMACK64 (disabled)"}
- {"dt": "2024-07-11T07:38:30.561872", "lvl": "target", "msg": "<6>[    1.377972] evm: security.SMACK64EXEC (disabled)"}
- {"dt": "2024-07-11T07:38:30.562128", "lvl": "target", "msg": "<6>[    1.378214] evm: security.SMACK64TRANSMUTE (disabled)"}
- {"dt": "2024-07-11T07:38:30.562374", "lvl": "target", "msg": "<6>[    1.378468] evm: security.SMACK64MMAP (disabled)"}
- {"dt": "2024-07-11T07:38:30.562573", "lvl": "target", "msg": "<6>[    1.378716] evm: security.apparmor"}
- {"dt": "2024-07-11T07:38:30.562753", "lvl": "target", "msg": "<6>[    1.378913] evm: security.ima"}
- {"dt": "2024-07-11T07:38:30.562948", "lvl": "target", "msg": "<6>[    1.379092] evm: security.capability"}
- {"dt": "2024-07-11T07:38:30.563261", "lvl": "target", "msg": "<6>[    1.379308] evm: HMAC attrs: 0x1"}
- {"dt": "2024-07-11T07:38:30.575943", "lvl": "target", "msg": "<6>[    1.391555] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-07-11T07:38:30.586669", "lvl": "target", "msg": "<6>[    1.402576] clk: Disabling unused clocks"}
- {"dt": "2024-07-11T07:38:30.587109", "lvl": "target", "msg": "<6>[    1.403186] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-07-11T07:38:30.588012", "lvl": "target", "msg": "<6>[    1.404026] ALSA device list:"}
- {"dt": "2024-07-11T07:38:30.588148", "lvl": "target", "msg": "<6>[    1.404297]   No soundcards found."}
- {"dt": "2024-07-11T07:38:30.629211", "lvl": "target", "msg": "<6>[    1.444322] EXT4-fs (vda): mounted filesystem 6a4cd5c7-4ab5-45d4-8820-40d209833431 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-07-11T07:38:30.630225", "lvl": "target", "msg": "<6>[    1.446014] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-07-11T07:38:30.634831", "lvl": "target", "msg": "<6>[    1.450818] devtmpfs: mounted"}
- {"dt": "2024-07-11T07:38:30.708851", "lvl": "target", "msg": "<6>[    1.524683] Freeing unused kernel memory: 14016K"}
- {"dt": "2024-07-11T07:38:30.897146", "lvl": "target", "msg": "<6>[    1.713034] Checked W+X mappings: passed, no W+X pages found"}
- {"dt": "2024-07-11T07:38:30.898868", "lvl": "target", "msg": "<6>[    1.714822] Run /sbin/init as init process"}
- {"dt": "2024-07-11T07:38:30.899216", "lvl": "target", "msg": "<7>[    1.715265]   with arguments:"}
- {"dt": "2024-07-11T07:38:30.899612", "lvl": "target", "msg": "<7>[    1.715543]     /sbin/init"}
- {"dt": "2024-07-11T07:38:30.899937", "lvl": "target", "msg": "<7>[    1.715991]     verbose"}
- {"dt": "2024-07-11T07:38:30.900094", "lvl": "target", "msg": "<7>[    1.716208]   with environment:"}
- {"dt": "2024-07-11T07:38:30.900303", "lvl": "target", "msg": "<7>[    1.716451]     HOME=/"}
- {"dt": "2024-07-11T07:38:30.900614", "lvl": "target", "msg": "<7>[    1.716659]     TERM=linux"}
- {"dt": "2024-07-11T07:38:30.929903", "lvl": "target", "msg": "<6>[    1.745654] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist."}
- {"dt": "2024-07-11T07:38:31.374609", "lvl": "target", "msg": "<30>[    2.189379] systemd[1]: Inserted module 'autofs4'"}
- {"dt": "2024-07-11T07:38:31.556689", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:38:31.557144", "lvl": "target", "msg": "Welcome to [1mDebian GNU/Linux 12 (bookworm)[0m!"}
- {"dt": "2024-07-11T07:38:31.557231", "lvl": "target", "msg": "\r"}
- {"dt": "2024-07-11T07:38:33.121147", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2024-07-11T07:38:33.129388", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modpr\u2026lice[0m - Slice /system/modprobe.\r"}
- {"dt": "2024-07-11T07:38:33.136453", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-seria\u2026[0m - Slice /system/serial-getty.\r"}
- {"dt": "2024-07-11T07:38:33.141851", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2024-07-11T07:38:33.145659", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026quests to Console Directory Watch.\r"}
- {"dt": "2024-07-11T07:38:33.147989", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-passwo\u2026 Requests to Wall Directory Watch.\r"}
- {"dt": "2024-07-11T07:38:33.154401", "lvl": "target", "msg": "[[0;32m  OK  [0m] Set up automount [0;1;39mproc-sys-\u2026rmats File System Automount Point.\r"}
- {"dt": "2024-07-11T07:38:33.156316", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2024-07-11T07:38:33.157676", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mcryptsetup.\u2026get[0m - Local Encrypted Volumes.\r"}
- {"dt": "2024-07-11T07:38:33.158739", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mintegrityse\u2026Local Integrity Protected Volumes.\r"}
- {"dt": "2024-07-11T07:38:33.159948", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2024-07-11T07:38:33.160970", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2024-07-11T07:38:33.162003", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2024-07-11T07:38:33.162820", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2024-07-11T07:38:33.163996", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mveritysetup\u2026 - Local Verity Protected Volumes.\r"}
- {"dt": "2024-07-11T07:38:33.167798", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initc\u2026 initctl Compatibility Named Pipe.\r"}
- {"dt": "2024-07-11T07:38:33.181368", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026socket[0m - Journal Audit Socket.\r"}
- {"dt": "2024-07-11T07:38:33.188488", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journ\u2026t[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2024-07-11T07:38:33.194436", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket.\r"}
- {"dt": "2024-07-11T07:38:33.202540", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026.socket[0m - udev Control Socket.\r"}
- {"dt": "2024-07-11T07:38:33.207295", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd\u2026l.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2024-07-11T07:38:33.250997", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System...\r"}
- {"dt": "2024-07-11T07:38:33.270536", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount\u2026POSIX Message Queue File System...\r"}
- {"dt": "2024-07-11T07:38:33.292933", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.\u2026[0m - Kernel Debug File System...\r"}
- {"dt": "2024-07-11T07:38:33.358072", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracin\u2026[0m - Kernel Trace File System...\r"}
- {"dt": "2024-07-11T07:38:33.444850", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes\u2026ate List of Static Device Nodes...\r"}
- {"dt": "2024-07-11T07:38:33.464876", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs\u2026m - Load Kernel Module configfs...\r"}
- {"dt": "2024-07-11T07:38:33.518513", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@dm_mod.s\u2026[0m - Load Kernel Module dm_mod...\r"}
- {"dt": "2024-07-11T07:38:33.548634", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2024-07-11T07:38:33.612813", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_psto\u2026- Load Kernel Module efi_pstore...\r"}
- {"dt": "2024-07-11T07:38:33.667049", "lvl": "target", "msg": "<4>[    4.482836] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log."}
- {"dt": "2024-07-11T07:38:33.668085", "lvl": "target", "msg": "<6>[    4.484148] device-mapper: uevent: version 1.0.3"}
- {"dt": "2024-07-11T07:38:33.671835", "lvl": "target", "msg": "<6>[    4.487651] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev"}
- {"dt": "2024-07-11T07:38:33.678350", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.ser\u2026e[0m - Load Kernel Module fuse...\r"}
- {"dt": "2024-07-11T07:38:33.762232", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@loop.ser\u2026e[0m - Load Kernel Module loop...\r"}
- {"dt": "2024-07-11T07:38:33.818524", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2024-07-11T07:38:33.833706", "lvl": "target", "msg": "<6>[    4.649625] fuse: init (API version 7.40)"}
- {"dt": "2024-07-11T07:38:33.886331", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-l\u2026rvice[0m - Load Kernel Modules...\r"}
- {"dt": "2024-07-11T07:38:33.914470", "lvl": "target", "msg": "<6>[    4.730386] loop: module loaded"}
- {"dt": "2024-07-11T07:38:33.950726", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-f\u2026nt Root and Kernel File Systems...\r"}
- {"dt": "2024-07-11T07:38:34.004440", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trig\u2026[0m - Coldplug All udev Devices...\r"}
- {"dt": "2024-07-11T07:38:34.157853", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System.\r"}
- {"dt": "2024-07-11T07:38:34.162458", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[\u2026- POSIX Message Queue File System.\r"}
- {"dt": "2024-07-11T07:38:34.170658", "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:38:34.175402", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing\u2026nt[0m - Kernel Trace File System.\r"}
- {"dt": "2024-07-11T07:38:34.184104", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes\u2026reate List of Static Device Nodes.\r"}
- {"dt": "2024-07-11T07:38:34.202831", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs\u2026[0m - Load Kernel Module configfs.\r"}
- {"dt": "2024-07-11T07:38:34.217356", "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:38:34.233170", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2024-07-11T07:38:34.246631", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_psto\u2026m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2024-07-11T07:38:34.258881", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2024-07-11T07:38:34.272610", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop.\r"}
- {"dt": "2024-07-11T07:38:34.286139", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-l\u2026service[0m - Load Kernel Modules.\r"}
- {"dt": "2024-07-11T07:38:34.303138", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-f\u2026ount Root and Kernel File Systems.\r"}
- {"dt": "2024-07-11T07:38:34.369837", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-conne\u2026[0m - FUSE Control File System...\r"}
- {"dt": "2024-07-11T07:38:34.445435", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config\u2026ernel Configuration File System...\r"}
- {"dt": "2024-07-11T07:38:34.555102", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-se\u2026ice[0m - Load/Save Random Seed...\r"}
- {"dt": "2024-07-11T07:38:34.670497", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.se\u2026ce[0m - Apply Kernel Variables...\r"}
- {"dt": "2024-07-11T07:38:34.765346", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.\u2026rvice[0m - Create System Users...\r"}
- {"dt": "2024-07-11T07:38:34.882705", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2024-07-11T07:38:34.934786", "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:38:34.942558", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.\u2026 Kernel Configuration File System.\r"}
- {"dt": "2024-07-11T07:38:34.952510", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-se\u2026rvice[0m - Load/Save Random Seed.\r"}
- {"dt": "2024-07-11T07:38:35.022235", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-f\u2026h Journal to Persistent Storage...\r"}
- {"dt": "2024-07-11T07:38:35.049077", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2024-07-11T07:38:35.160927", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2024-07-11T07:38:35.234384", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026ate Static Device Nodes in /dev...\r"}
- {"dt": "2024-07-11T07:38:35.325154", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-f\u2026ush Journal to Persistent Storage.\r"}
- {"dt": "2024-07-11T07:38:35.488898", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026reate Static Device Nodes in /dev.\r"}
- {"dt": "2024-07-11T07:38:35.491571", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pr\u2026reparation for Local File Systems.\r"}
- {"dt": "2024-07-11T07:38:35.492862", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2024-07-11T07:38:35.569803", "lvl": "target", "msg": "         Starting [0;1;39msystemd-binfmt.se\u2026et Up Additional Binary Formats...\r"}
- {"dt": "2024-07-11T07:38:35.623475", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-\u2026 Volatile Files and Directories...\r"}
- {"dt": "2024-07-11T07:38:35.717661", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.ser\u2026ger for Device Events and Files...\r"}
- {"dt": "2024-07-11T07:38:35.941933", "lvl": "target", "msg": "         Mounting [0;1;39mproc-sys-fs-binfm\u2026utable File Formats File System...\r"}
- {"dt": "2024-07-11T07:38:36.216517", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mproc-sys-fs-binfmt\u2026ecutable File Formats File System.\r"}
- {"dt": "2024-07-11T07:38:36.247311", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-\u2026te Volatile Files and Directories.\r"}
- {"dt": "2024-07-11T07:38:36.283054", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026rd System Boot/Shutdown in UTMP...\r"}
- {"dt": "2024-07-11T07:38:36.310551", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-binfmt.se\u2026 Set Up Additional Binary Formats.\r"}
- {"dt": "2024-07-11T07:38:36.477926", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026cord System Boot/Shutdown in UTMP.\r"}
- {"dt": "2024-07-11T07:38:36.529732", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.serv\u2026nager for Device Events and Files.\r"}
- {"dt": "2024-07-11T07:38:37.950045", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trig\u2026e[0m - Coldplug All udev Devices.\r"}
- {"dt": "2024-07-11T07:38:37.952810", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2024-07-11T07:38:37.961849", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2024-07-11T07:38:37.965268", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.\u2026 apt upgrade and clean activities.\r"}
- {"dt": "2024-07-11T07:38:37.967050", "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:38:37.972603", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer\u2026etadata Check for All Filesystems.\r"}
- {"dt": "2024-07-11T07:38:37.975568", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused blocks once a week.\r"}
- {"dt": "2024-07-11T07:38:37.977219", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-c\u2026 Cleanup of Temporary Directories.\r"}
- {"dt": "2024-07-11T07:38:37.977924", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2024-07-11T07:38:37.980948", "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:38:37.981804", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2024-07-11T07:38:37.988734", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2024-07-11T07:38:38.026614", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2024-07-11T07:38:38.063146", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.serv\u2026e ext4 Metadata Check Snapshots...\r"}
- {"dt": "2024-07-11T07:38:38.209689", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.se\u2026ice[0m - User Login Management...\r"}
- {"dt": "2024-07-11T07:38:38.294408", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sess\u2026vice[0m - Permit User Sessions...\r"}
- {"dt": "2024-07-11T07:38:38.405118", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2024-07-11T07:38:38.513820", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2024-07-11T07:38:38.566232", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sess\u2026ervice[0m - Permit User Sessions.\r"}
- {"dt": "2024-07-11T07:38:38.659509", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2024-07-11T07:38:38.723007", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAM\u2026ice[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2024-07-11T07:38:38.737962", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2024-07-11T07:38:38.973418", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.serv\u2026ine ext4 Metadata Check Snapshots.\r"}
- {"dt": "2024-07-11T07:38:39.251185", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2024-07-11T07:38:39.266321", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2024-07-11T07:38:39.273395", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2024-07-11T07:38:39.335689", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-ut\u2026 Record Runlevel Change in UTMP...\r"}
- {"dt": "2024-07-11T07:38:39.480558", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-ut\u2026 - Record Runlevel Change in UTMP.\r"}
- {"dt": "2024-07-11T07:38:40.641090", "lvl": "target", "msg": "\r"}
- {"dt": "2024-07-11T07:38:40.641565", "lvl": "target", "msg": "Debian GNU/Linux 12 runner-vwmj3eza-project-40964107-concurrent-1 ttyAMA0"}
- {"dt": "2024-07-11T07:38:40.641659", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:38:40.642083", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2024-07-11T07:38:40.642190", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "12.02", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2024-07-11T07:38:40.642369", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-07-11T07:38:40.642459", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2024-07-11T07:38:40.742771", "lvl": "input", "msg": "root"}
- {"dt": "2024-07-11T07:38:40.743036", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:38:40.743140", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:29:48)"}
- {"dt": "2024-07-11T07:38:40.743230", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:54)"}
- {"dt": "2024-07-11T07:38:40.746951", "lvl": "target", "msg": "runner-vwmj3eza-project-40964107-concurrent-1 login: root\r"}
- {"dt": "2024-07-11T07:38:41.131395", "lvl": "target", "msg": "Linux runner-vwmj3eza-project-40964107-concurrent-1 6.10.0-rc7-next-20240710 #1 SMP @1720612166 aarch64"}
- {"dt": "2024-07-11T07:38:41.136003", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:38:41.136330", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2024-07-11T07:38:41.136449", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2024-07-11T07:38:41.136822", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2024-07-11T07:38:41.136906", "lvl": "target", "msg": ""}
- {"dt": "2024-07-11T07:38:41.136992", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2024-07-11T07:38:41.137496", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2024-07-11T07:38:43.584074", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:38:43.584266", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:15) [common]"}
- {"dt": "2024-07-11T07:38:43.584342", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "14.97", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2024-07-11T07:38:43.584466", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:15) [common]"}
- {"dt": "2024-07-11T07:38:43.584555", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:29:45) [common]"}
- {"dt": "2024-07-11T07:38:43.584620", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:38:43.584673", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2024-07-11T07:38:43.584729", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2024-07-11T07:38:43.634922", "lvl": "input", "msg": ""}
- {"dt": "2024-07-11T07:38:43.635173", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:30:00)"}
- {"dt": "2024-07-11T07:38:43.635270", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2024-07-11T07:38:43.637029", "lvl": "target", "msg": "[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# "}
- {"dt": "2024-07-11T07:38:43.642348", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:43.642523", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:29:45) [common]"}
- {"dt": "2024-07-11T07:38:43.642687", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:43.642837", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:15) [common]"}
- {"dt": "2024-07-11T07:38:43.642980", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T07:38:43.643125", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:10:00) [common]"}
- {"dt": "2024-07-11T07:38:43.643249", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-07-11T07:38:43.643370", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2024-07-11T07:38:43.743670", "lvl": "input", "msg": "#"}
- {"dt": "2024-07-11T07:38:43.743933", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:10:00)"}
- {"dt": "2024-07-11T07:38:43.745347", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# #"}
- {"dt": "2024-07-11T07:38:43.752653", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2024-07-11T07:38:43.752876", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2024-07-11T07:38:43.853241", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-07-11T07:38:43.854739", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# export SHELL=/bin/sh"}
- {"dt": "2024-07-11T07:38:43.862607", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2024-07-11T07:38:43.963006", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2024-07-11T07:38:43.964364", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# . /lava-1/environment"}
- {"dt": "2024-07-11T07:38:43.971301", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2024-07-11T07:38:44.071624", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2024-07-11T07:38:44.071862", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-07-11T07:38:44.072919", "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:38:44.540330", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_commands"}
- {"dt": "2024-07-11T07:38:44.541253", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_commands"}
- {"dt": "2024-07-11T07:38:44.549208", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2024-07-11T07:38:44.589989", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2024-07-11T07:38:44.590619", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-07-11T07:38:44.593119", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2024-07-11T07:38:44.593202", "lvl": "info", "msg": "Starting test lava.0_commands (1_1.1.3.1)"}
- {"dt": "2024-07-11T07:38:44.593292", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-07-11T07:38:44.593386", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2024-07-11T07:38:44.594154", "lvl": "target", "msg": "+ lava-test-case command --shell dmesg"}
- {"dt": "2024-07-11T07:38:44.625750", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTTC command>"}
- {"dt": "2024-07-11T07:38:44.626141", "lvl": "debug", "msg": "Received signal: <STARTTC> command"}
- {"dt": "2024-07-11T07:38:44.699292", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-07-11T07:38:44.700317", "lvl": "target", "msg": "[    0.000000] Linux version 6.10.0-rc7-next-20240710 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP @1720612166"}
- {"dt": "2024-07-11T07:38:44.700459", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-07-11T07:38:44.700763", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-07-11T07:38:44.700902", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-07-11T07:38:44.701214", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-07-11T07:38:44.701549", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-07-11T07:38:44.701660", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-07-11T07:38:44.701879", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-07-11T07:38:44.702311", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:44.702646", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f5fb240-0x13f5fdfff]"}
- {"dt": "2024-07-11T07:38:44.702760", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-07-11T07:38:44.703161", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-07-11T07:38:44.703276", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-07-11T07:38:44.703644", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:44.704066", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-07-11T07:38:44.704179", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-07-11T07:38:44.704593", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:44.704920", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-07-11T07:38:44.705250", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fc000000 on node -1"}
- {"dt": "2024-07-11T07:38:44.705374", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-07-11T07:38:44.705672", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-07-11T07:38:44.705978", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-07-11T07:38:44.706419", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-07-11T07:38:44.706550", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-07-11T07:38:44.706865", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 34 pages/cpu s100440 r8192 d30632 u139264"}
- {"dt": "2024-07-11T07:38:44.707189", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s100440 r8192 d30632 u139264 alloc=34*4096"}
- {"dt": "2024-07-11T07:38:44.707571", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-07-11T07:38:44.707920", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-07-11T07:38:44.708336", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:38:44.708687", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-07-11T07:38:44.708825", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-07-11T07:38:44.709303", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-07-11T07:38:44.709437", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-07-11T07:38:44.709944", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-07-11T07:38:44.710079", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-07-11T07:38:44.710500", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-07-11T07:38:44.711037", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2024-07-11T07:38:44.711367", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-07-11T07:38:44.711729", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.712111", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.712238", "lvl": "target", "msg": "[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-07-11T07:38:44.712434", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-07-11T07:38:44.712625", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-07-11T07:38:44.712887", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off"}
- {"dt": "2024-07-11T07:38:44.713091", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-07-11T07:38:44.713632", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000f8000000-0x00000000fc000000] (64MB)"}
- {"dt": "2024-07-11T07:38:44.713769", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-07-11T07:38:44.714247", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 65320 entries in 256 pages"}
- {"dt": "2024-07-11T07:38:44.714398", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 256 pages with 1 groups"}
- {"dt": "2024-07-11T07:38:44.714806", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-07-11T07:38:44.714941", "lvl": "target", "msg": "[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-07-11T07:38:44.715840", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2024-07-11T07:38:44.715973", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:38:44.716271", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-07-11T07:38:44.716832", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-07-11T07:38:44.717173", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-07-11T07:38:44.717556", "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:38:44.717932", "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:38:44.718063", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-07-11T07:38:44.718532", "lvl": "target", "msg": "[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-07-11T07:38:44.718663", "lvl": "target", "msg": "[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-07-11T07:38:44.718862", "lvl": "target", "msg": "[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-07-11T07:38:44.719290", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-07-11T07:38:44.719411", "lvl": "target", "msg": "[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-07-11T07:38:44.719884", "lvl": "target", "msg": "[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-07-11T07:38:44.720243", "lvl": "target", "msg": "[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-07-11T07:38:44.720359", "lvl": "target", "msg": "[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-07-11T07:38:44.720917", "lvl": "target", "msg": "[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100210000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:38:44.721183", "lvl": "target", "msg": "[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100220000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-07-11T07:38:44.721663", "lvl": "target", "msg": "[    0.000000] GICv3: using LPI property table @0x0000000100230000"}
- {"dt": "2024-07-11T07:38:44.721781", "lvl": "target", "msg": "[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100240000"}
- {"dt": "2024-07-11T07:38:44.722077", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-07-11T07:38:44.722562", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-07-11T07:38:44.723043", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-07-11T07:38:44.723420", "lvl": "target", "msg": "[    0.000060] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-07-11T07:38:44.723906", "lvl": "target", "msg": "[    0.002973] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)"}
- {"dt": "2024-07-11T07:38:44.724023", "lvl": "target", "msg": "[    0.014460] Console: colour dummy device 80x25"}
- {"dt": "2024-07-11T07:38:44.724671", "lvl": "target", "msg": "[    0.022302] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-07-11T07:38:44.724787", "lvl": "target", "msg": "[    0.023139] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-07-11T07:38:44.725344", "lvl": "target", "msg": "[    0.026927] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,tomoyo,bpf,ima,evm"}
- {"dt": "2024-07-11T07:38:44.725458", "lvl": "target", "msg": "[    0.028228] landlock: Up and running."}
- {"dt": "2024-07-11T07:38:44.725624", "lvl": "target", "msg": "[    0.028376] Yama: becoming mindful."}
- {"dt": "2024-07-11T07:38:44.725993", "lvl": "target", "msg": "[    0.031285] AppArmor: AppArmor initialized"}
- {"dt": "2024-07-11T07:38:44.726108", "lvl": "target", "msg": "[    0.031497] TOMOYO Linux initialized"}
- {"dt": "2024-07-11T07:38:44.726294", "lvl": "target", "msg": "[    0.032229] LSM support for eBPF active"}
- {"dt": "2024-07-11T07:38:44.726810", "lvl": "target", "msg": "[    0.035728] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.727160", "lvl": "target", "msg": "[    0.036052] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.727310", "lvl": "target", "msg": "[    0.068178] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-07-11T07:38:44.727665", "lvl": "target", "msg": "[    0.081666] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-07-11T07:38:44.728023", "lvl": "target", "msg": "[    0.081893] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-07-11T07:38:44.728432", "lvl": "target", "msg": "[    0.091854] Platform MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:44.728547", "lvl": "target", "msg": "[    0.092486] PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:44.728978", "lvl": "target", "msg": "[    0.093126] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-07-11T07:38:44.729094", "lvl": "target", "msg": "[    0.096092] EFI services will not be available."}
- {"dt": "2024-07-11T07:38:44.729511", "lvl": "target", "msg": "[    0.098276] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-07-11T07:38:44.729643", "lvl": "target", "msg": "[    0.106714] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-07-11T07:38:44.730106", "lvl": "target", "msg": "[    0.110750] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-07-11T07:38:44.730303", "lvl": "target", "msg": "[    0.112525] GICv3: CPU1: using allocated LPI pending table @0x0000000100250000"}
- {"dt": "2024-07-11T07:38:44.730805", "lvl": "target", "msg": "[    0.116621] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-07-11T07:38:44.730919", "lvl": "target", "msg": "[    0.124586] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-07-11T07:38:44.731309", "lvl": "target", "msg": "[    0.125504] SMP: Total of 2 processors activated."}
- {"dt": "2024-07-11T07:38:44.731434", "lvl": "target", "msg": "[    0.125689] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-07-11T07:38:44.732025", "lvl": "target", "msg": "[    0.125899] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-07-11T07:38:44.732309", "lvl": "target", "msg": "[    0.126141] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-07-11T07:38:44.732596", "lvl": "target", "msg": "[    0.126325] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-07-11T07:38:44.732903", "lvl": "target", "msg": "[    0.126505] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-07-11T07:38:44.733321", "lvl": "target", "msg": "[    0.126745] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-07-11T07:38:44.733654", "lvl": "target", "msg": "[    0.127059] CPU features: detected: Common not Private translations"}
- {"dt": "2024-07-11T07:38:44.733891", "lvl": "target", "msg": "[    0.127267] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-07-11T07:38:44.734254", "lvl": "target", "msg": "[    0.127435] CPU features: detected: Data cache clean to Point of Deep Persistence"}
- {"dt": "2024-07-11T07:38:44.734549", "lvl": "target", "msg": "[    0.127749] CPU features: detected: Data cache clean to Point of Persistence"}
- {"dt": "2024-07-11T07:38:44.734833", "lvl": "target", "msg": "[    0.128118] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-07-11T07:38:44.734888", "lvl": "target", "msg": "[    0.128346] CPU features: detected: E0PD"}
- {"dt": "2024-07-11T07:38:44.735296", "lvl": "target", "msg": "[    0.128491] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-07-11T07:38:44.735548", "lvl": "target", "msg": "[    0.128702] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-07-11T07:38:44.735772", "lvl": "target", "msg": "[    0.128902] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-07-11T07:38:44.736173", "lvl": "target", "msg": "[    0.129138] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-07-11T07:38:44.736435", "lvl": "target", "msg": "[    0.129453] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-07-11T07:38:44.736713", "lvl": "target", "msg": "[    0.129685] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-07-11T07:38:44.736975", "lvl": "target", "msg": "[    0.129915] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-07-11T07:38:44.737262", "lvl": "target", "msg": "[    0.130097] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2024-07-11T07:38:44.737513", "lvl": "target", "msg": "[    0.130314] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-07-11T07:38:44.737749", "lvl": "target", "msg": "[    0.130508] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-07-11T07:38:44.737992", "lvl": "target", "msg": "[    0.130700] CPU features: detected: Random Number Generator"}
- {"dt": "2024-07-11T07:38:44.738298", "lvl": "target", "msg": "[    0.130891] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-07-11T07:38:44.738575", "lvl": "target", "msg": "[    0.131077] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-07-11T07:38:44.738883", "lvl": "target", "msg": "[    0.131263] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2024-07-11T07:38:44.739168", "lvl": "target", "msg": "[    0.131513] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-07-11T07:38:44.739491", "lvl": "target", "msg": "[    0.131753] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-07-11T07:38:44.739832", "lvl": "target", "msg": "[    0.132011] CPU features: detected: FA64"}
- {"dt": "2024-07-11T07:38:44.740181", "lvl": "target", "msg": "[    0.132214] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-07-11T07:38:44.740488", "lvl": "target", "msg": "[    0.132437] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-07-11T07:38:44.740736", "lvl": "target", "msg": "[    0.148645] alternatives: applying system-wide alternatives"}
- {"dt": "2024-07-11T07:38:44.741020", "lvl": "target", "msg": "[    0.166478] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-07-11T07:38:44.741290", "lvl": "target", "msg": "[    0.167382] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:38:44.741534", "lvl": "target", "msg": "[    0.167607] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-07-11T07:38:44.741800", "lvl": "target", "msg": "[    0.169072] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-07-11T07:38:44.742065", "lvl": "target", "msg": "[    0.169290] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-07-11T07:38:44.742314", "lvl": "target", "msg": "[    0.169496] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-07-11T07:38:44.742548", "lvl": "target", "msg": "[    0.210120] node 0 deferred pages initialised in 8ms"}
- {"dt": "2024-07-11T07:38:44.743042", "lvl": "target", "msg": "[    0.211861] Memory: 3920168K/4194304K available (20096K kernel code, 6228K rwdata, 12880K rodata, 14016K init, 982K bss, 204312K reserved, 65536K cma-reserved)"}
- {"dt": "2024-07-11T07:38:44.743104", "lvl": "target", "msg": "[    0.244370] devtmpfs: initialized"}
- {"dt": "2024-07-11T07:38:44.743537", "lvl": "target", "msg": "[    0.275050] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-07-11T07:38:44.743945", "lvl": "target", "msg": "[    0.276532] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.744210", "lvl": "target", "msg": "[    0.282044] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-07-11T07:38:44.744277", "lvl": "target", "msg": "[    0.282730] 0 pages in range for non-PLT usage"}
- {"dt": "2024-07-11T07:38:44.744504", "lvl": "target", "msg": "[    0.282887] 510672 pages in range for PLT usage"}
- {"dt": "2024-07-11T07:38:44.744728", "lvl": "target", "msg": "[    0.290970] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-07-11T07:38:44.744954", "lvl": "target", "msg": "[    0.306090] DMI not present or invalid."}
- {"dt": "2024-07-11T07:38:44.745179", "lvl": "target", "msg": "[    0.337551] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-07-11T07:38:44.745599", "lvl": "target", "msg": "[    0.351164] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:44.745914", "lvl": "target", "msg": "[    0.352324] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:44.746210", "lvl": "target", "msg": "[    0.354145] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-07-11T07:38:44.746455", "lvl": "target", "msg": "[    0.354864] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-07-11T07:38:44.746763", "lvl": "target", "msg": "[    0.358126] audit: type=2000 audit(0.324:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-07-11T07:38:44.747025", "lvl": "target", "msg": "[    0.369136] thermal_sys: Registered thermal governor 'fair_share'"}
- {"dt": "2024-07-11T07:38:44.747283", "lvl": "target", "msg": "[    0.369237] thermal_sys: Registered thermal governor 'bang_bang'"}
- {"dt": "2024-07-11T07:38:44.747538", "lvl": "target", "msg": "[    0.369643] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-07-11T07:38:44.747930", "lvl": "target", "msg": "[    0.370216] thermal_sys: Registered thermal governor 'user_space'"}
- {"dt": "2024-07-11T07:38:44.748209", "lvl": "target", "msg": "[    0.370666] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-07-11T07:38:44.748268", "lvl": "target", "msg": "[    0.372312] cpuidle: using governor ladder"}
- {"dt": "2024-07-11T07:38:44.748492", "lvl": "target", "msg": "[    0.373504] cpuidle: using governor menu"}
- {"dt": "2024-07-11T07:38:44.748883", "lvl": "target", "msg": "[    0.377572] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-07-11T07:38:44.749114", "lvl": "target", "msg": "[    0.379011] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-07-11T07:38:44.749172", "lvl": "target", "msg": "[    0.395385] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-07-11T07:38:44.749661", "lvl": "target", "msg": "[    0.495309] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-07-11T07:38:44.749893", "lvl": "target", "msg": "[    0.497154] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-07-11T07:38:44.750123", "lvl": "target", "msg": "[    0.497743] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-07-11T07:38:44.750407", "lvl": "target", "msg": "[    0.527081] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:44.750681", "lvl": "target", "msg": "[    0.527342] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-07-11T07:38:44.750958", "lvl": "target", "msg": "[    0.527547] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:44.751219", "lvl": "target", "msg": "[    0.527754] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-07-11T07:38:44.751503", "lvl": "target", "msg": "[    0.527959] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:44.751731", "lvl": "target", "msg": "[    0.528168] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-07-11T07:38:44.752159", "lvl": "target", "msg": "[    0.528497] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-07-11T07:38:44.752434", "lvl": "target", "msg": "[    0.528710] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-07-11T07:38:44.752493", "lvl": "target", "msg": "[    0.538132] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-07-11T07:38:44.752718", "lvl": "target", "msg": "[    0.547994] ACPI: Interpreter disabled."}
- {"dt": "2024-07-11T07:38:44.752942", "lvl": "target", "msg": "[    0.561806] iommu: Default domain type: Translated"}
- {"dt": "2024-07-11T07:38:44.753172", "lvl": "target", "msg": "[    0.562078] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-07-11T07:38:44.753394", "lvl": "target", "msg": "[    0.565392] SCSI subsystem initialized"}
- {"dt": "2024-07-11T07:38:44.753615", "lvl": "target", "msg": "[    0.569866] mc: Linux media interface: v0.10"}
- {"dt": "2024-07-11T07:38:44.753835", "lvl": "target", "msg": "[    0.570252] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-07-11T07:38:44.754055", "lvl": "target", "msg": "[    0.570748] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-07-11T07:38:44.754544", "lvl": "target", "msg": "[    0.570922] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-07-11T07:38:44.754600", "lvl": "target", "msg": "[    0.571258] PTP clock support registered"}
- {"dt": "2024-07-11T07:38:44.754825", "lvl": "target", "msg": "[    0.572244] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-07-11T07:38:44.755155", "lvl": "target", "msg": "[    0.574163] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-07-11T07:38:44.755209", "lvl": "target", "msg": "[    0.578982] FPGA manager framework"}
- {"dt": "2024-07-11T07:38:44.755681", "lvl": "target", "msg": "[    0.579702] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-07-11T07:38:44.755840", "lvl": "target", "msg": "[    0.588865] NetLabel: Initializing"}
- {"dt": "2024-07-11T07:38:44.756038", "lvl": "target", "msg": "[    0.589029] NetLabel:  domain hash size = 128"}
- {"dt": "2024-07-11T07:38:44.756236", "lvl": "target", "msg": "[    0.589182] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2024-07-11T07:38:44.756464", "lvl": "target", "msg": "[    0.590352] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2024-07-11T07:38:44.756640", "lvl": "target", "msg": "[    0.592090] vgaarb: loaded"}
- {"dt": "2024-07-11T07:38:44.757067", "lvl": "target", "msg": "[    0.596040] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-07-11T07:38:44.757190", "lvl": "target", "msg": "[    0.602914] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-07-11T07:38:44.757666", "lvl": "target", "msg": "[    0.603329] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-07-11T07:38:44.757786", "lvl": "target", "msg": "[    0.605732] netfs: FS-Cache loaded"}
- {"dt": "2024-07-11T07:38:44.758155", "lvl": "target", "msg": "[    0.609952] AppArmor: AppArmor Filesystem Enabled"}
- {"dt": "2024-07-11T07:38:44.758287", "lvl": "target", "msg": "[    0.610484] pnp: PnP ACPI: disabled"}
- {"dt": "2024-07-11T07:38:44.758480", "lvl": "target", "msg": "[    0.647997] NET: Registered PF_INET protocol family"}
- {"dt": "2024-07-11T07:38:44.759010", "lvl": "target", "msg": "[    0.649550] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.759261", "lvl": "target", "msg": "[    0.658339] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.759834", "lvl": "target", "msg": "[    0.658916] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.760280", "lvl": "target", "msg": "[    0.659352] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.760674", "lvl": "target", "msg": "[    0.660466] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.760830", "lvl": "target", "msg": "[    0.661197] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-07-11T07:38:44.761390", "lvl": "target", "msg": "[    0.663046] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.761565", "lvl": "target", "msg": "[    0.663646] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.762079", "lvl": "target", "msg": "[    0.664241] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-07-11T07:38:44.762238", "lvl": "target", "msg": "[    0.665853] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-07-11T07:38:44.762638", "lvl": "target", "msg": "[    0.666490] NET: Registered PF_XDP protocol family"}
- {"dt": "2024-07-11T07:38:44.762729", "lvl": "target", "msg": "[    0.666812] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-07-11T07:38:44.763173", "lvl": "target", "msg": "[    0.681162] kvm [1]: nv: 529 coarse grained trap handlers"}
- {"dt": "2024-07-11T07:38:44.763466", "lvl": "target", "msg": "[    0.682238] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2024-07-11T07:38:44.763791", "lvl": "target", "msg": "[    0.683283] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-07-11T07:38:44.764053", "lvl": "target", "msg": "[    0.684124] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-07-11T07:38:44.764322", "lvl": "target", "msg": "[    0.684384] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-07-11T07:38:44.764614", "lvl": "target", "msg": "[    0.684757] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-07-11T07:38:44.764856", "lvl": "target", "msg": "[    0.685843] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-07-11T07:38:44.765139", "lvl": "target", "msg": "[    0.686510] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-07-11T07:38:44.765402", "lvl": "target", "msg": "[    0.692543] Initialise system trusted keyrings"}
- {"dt": "2024-07-11T07:38:44.765642", "lvl": "target", "msg": "[    0.694068] Key type blacklist registered"}
- {"dt": "2024-07-11T07:38:44.765959", "lvl": "target", "msg": "[    0.696165] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-07-11T07:38:44.766227", "lvl": "target", "msg": "[    0.708071] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-07-11T07:38:44.766479", "lvl": "target", "msg": "[    0.712187] integrity: Platform Keyring initialized"}
- {"dt": "2024-07-11T07:38:44.766712", "lvl": "target", "msg": "[    0.712901] integrity: Machine keyring initialized"}
- {"dt": "2024-07-11T07:38:44.766943", "lvl": "target", "msg": "[    0.787754] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-07-11T07:38:44.767003", "lvl": "target", "msg": "[    0.788166] Key type asymmetric registered"}
- {"dt": "2024-07-11T07:38:44.767229", "lvl": "target", "msg": "[    0.788406] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-07-11T07:38:44.767682", "lvl": "target", "msg": "[    0.789062] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)"}
- {"dt": "2024-07-11T07:38:44.767982", "lvl": "target", "msg": "[    0.790932] io scheduler mq-deadline registered"}
- {"dt": "2024-07-11T07:38:44.768247", "lvl": "target", "msg": "[    0.841799] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-07-11T07:38:44.768556", "lvl": "target", "msg": "[    0.853009] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-07-11T07:38:44.768978", "lvl": "target", "msg": "[    0.853861] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-07-11T07:38:44.769404", "lvl": "target", "msg": "[    0.854609] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-07-11T07:38:44.769845", "lvl": "target", "msg": "[    0.854954] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-07-11T07:38:44.770284", "lvl": "target", "msg": "[    0.855838] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-07-11T07:38:44.770646", "lvl": "target", "msg": "[    0.856650] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-07-11T07:38:44.770948", "lvl": "target", "msg": "[    0.858199] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-07-11T07:38:44.771204", "lvl": "target", "msg": "[    0.858705] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-07-11T07:38:44.771482", "lvl": "target", "msg": "[    0.858948] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:38:44.771843", "lvl": "target", "msg": "[    0.859179] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:38:44.772150", "lvl": "target", "msg": "[    0.859396] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:38:44.772483", "lvl": "target", "msg": "[    0.861077] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-07-11T07:38:44.772735", "lvl": "target", "msg": "[    0.866106] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-07-11T07:38:44.773011", "lvl": "target", "msg": "[    0.866333] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-07-11T07:38:44.773297", "lvl": "target", "msg": "[    0.866570] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-07-11T07:38:44.773580", "lvl": "target", "msg": "[    1.034166] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-07-11T07:38:44.773640", "lvl": "target", "msg": "[    1.047501] Serial: AMBA driver"}
- {"dt": "2024-07-11T07:38:44.773865", "lvl": "target", "msg": "[    1.051158] msm_serial: driver initialized"}
- {"dt": "2024-07-11T07:38:44.774207", "lvl": "target", "msg": "[    1.052951] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-07-11T07:38:44.774456", "lvl": "target", "msg": "[    1.053776] STM32 USART driver initialized"}
- {"dt": "2024-07-11T07:38:44.774798", "lvl": "target", "msg": "[    1.065340] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-07-11T07:38:44.775133", "lvl": "target", "msg": "[    1.072696] virtio_blk virtio0: [vda] 6059648 512-byte logical blocks (3.10 GB/2.89 GiB)"}
- {"dt": "2024-07-11T07:38:44.775379", "lvl": "target", "msg": "[    1.092400] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-07-11T07:38:44.775841", "lvl": "target", "msg": "[    1.098174] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-07-11T07:38:44.776329", "lvl": "target", "msg": "[    1.099849] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:38:44.776751", "lvl": "target", "msg": "[    1.100549] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:38:44.776992", "lvl": "target", "msg": "[    1.101371] Using buffer write method"}
- {"dt": "2024-07-11T07:38:44.777327", "lvl": "target", "msg": "[    1.101814] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:38:44.777748", "lvl": "target", "msg": "[    1.102225] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-07-11T07:38:44.778173", "lvl": "target", "msg": "[    1.103011] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-07-11T07:38:44.778448", "lvl": "target", "msg": "[    1.103437] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-07-11T07:38:44.778700", "lvl": "target", "msg": "[    1.104176] Using buffer write method"}
- {"dt": "2024-07-11T07:38:44.779025", "lvl": "target", "msg": "[    1.104388] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-07-11T07:38:44.779266", "lvl": "target", "msg": "[    1.104724] Concatenating MTD devices:"}
- {"dt": "2024-07-11T07:38:44.779328", "lvl": "target", "msg": "[    1.104914] (0): \"0.flash\""}
- {"dt": "2024-07-11T07:38:44.779741", "lvl": "target", "msg": "[    1.105063] (1): \"0.flash\""}
- {"dt": "2024-07-11T07:38:44.779981", "lvl": "target", "msg": "[    1.105211] into device \"0.flash\""}
- {"dt": "2024-07-11T07:38:44.780255", "lvl": "target", "msg": "[    1.136977] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:38:44.780590", "lvl": "target", "msg": "[    1.137207] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-07-11T07:38:44.780861", "lvl": "target", "msg": "[    1.137510] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-07-11T07:38:44.781243", "lvl": "target", "msg": "[    1.137709] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-07-11T07:38:44.781533", "lvl": "target", "msg": "[    1.138069] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-07-11T07:38:44.781871", "lvl": "target", "msg": "[    1.138274] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-07-11T07:38:44.782211", "lvl": "target", "msg": "[    1.138627] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-07-11T07:38:44.782554", "lvl": "target", "msg": "[    1.138906] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-07-11T07:38:44.782835", "lvl": "target", "msg": "[    1.149020] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-07-11T07:38:44.783265", "lvl": "target", "msg": "[    1.149890] rtc-pl031 9010000.pl031: setting system clock to 2024-07-11T07:38:30 UTC (1720683510)"}
- {"dt": "2024-07-11T07:38:44.783509", "lvl": "target", "msg": "[    1.153529] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-07-11T07:38:44.783973", "lvl": "target", "msg": "[    1.186445] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-07-11T07:38:44.784245", "lvl": "target", "msg": "[    1.186765] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-07-11T07:38:44.784578", "lvl": "target", "msg": "[    1.190037] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-07-11T07:38:44.784942", "lvl": "target", "msg": "[    1.194477] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-07-11T07:38:44.785228", "lvl": "target", "msg": "[    1.203483] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-07-11T07:38:44.785687", "lvl": "target", "msg": "[    1.232563] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2024-07-11T07:38:44.786029", "lvl": "target", "msg": "[    1.233929] watchdog: Delayed init of the lockup detector failed: -19"}
- {"dt": "2024-07-11T07:38:44.786313", "lvl": "target", "msg": "[    1.234239] watchdog: Hard watchdog permanently disabled"}
- {"dt": "2024-07-11T07:38:44.786713", "lvl": "target", "msg": "[    1.240005]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2024-07-11T07:38:44.787001", "lvl": "target", "msg": "[    1.264043] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-07-11T07:38:44.787089", "lvl": "target", "msg": "[    1.278379] Segment Routing with IPv6"}
- {"dt": "2024-07-11T07:38:44.787350", "lvl": "target", "msg": "[    1.278912] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-07-11T07:38:44.787612", "lvl": "target", "msg": "[    1.279530] mip6: Mobile IPv6"}
- {"dt": "2024-07-11T07:38:44.788045", "lvl": "target", "msg": "[    1.279983] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-07-11T07:38:44.788312", "lvl": "target", "msg": "[    1.281391] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-07-11T07:38:44.788572", "lvl": "target", "msg": "[    1.282156] Key type dns_resolver registered"}
- {"dt": "2024-07-11T07:38:44.788957", "lvl": "target", "msg": "[    1.326371] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-07-11T07:38:44.789195", "lvl": "target", "msg": "[    1.327243] Timer migration setup failed"}
- {"dt": "2024-07-11T07:38:44.789433", "lvl": "target", "msg": "[    1.327862] registered taskstats version 1"}
- {"dt": "2024-07-11T07:38:44.789711", "lvl": "target", "msg": "[    1.329259] Loading compiled-in X.509 certificates"}
- {"dt": "2024-07-11T07:38:44.790185", "lvl": "target", "msg": "[    1.338606] Loaded X.509 cert 'Build time autogenerated kernel key: 169ffd8f52f31ca9323fea8abf532340901819c3'"}
- {"dt": "2024-07-11T07:38:44.790457", "lvl": "target", "msg": "[    1.365468] Demotion targets for Node 0: null"}
- {"dt": "2024-07-11T07:38:44.790746", "lvl": "target", "msg": "[    1.367934] Key type .fscrypt registered"}
- {"dt": "2024-07-11T07:38:44.790846", "lvl": "target", "msg": "[    1.368194] Key type fscrypt-provisioning registered"}
- {"dt": "2024-07-11T07:38:44.791108", "lvl": "target", "msg": "[    1.370636] Key type encrypted registered"}
- {"dt": "2024-07-11T07:38:44.791536", "lvl": "target", "msg": "[    1.370943] AppArmor: AppArmor sha256 policy hashing enabled"}
- {"dt": "2024-07-11T07:38:44.791955", "lvl": "target", "msg": "[    1.372186] ima: No TPM chip found, activating TPM-bypass!"}
- {"dt": "2024-07-11T07:38:44.792249", "lvl": "target", "msg": "[    1.372532] ima: Allocated hash algorithm: sha256"}
- {"dt": "2024-07-11T07:38:44.792528", "lvl": "target", "msg": "[    1.375635] ima: No architecture policies found"}
- {"dt": "2024-07-11T07:38:44.792820", "lvl": "target", "msg": "[    1.377243] evm: Initialising EVM extended attributes:"}
- {"dt": "2024-07-11T07:38:44.792915", "lvl": "target", "msg": "[    1.377518] evm: security.selinux"}
- {"dt": "2024-07-11T07:38:44.793305", "lvl": "target", "msg": "[    1.377741] evm: security.SMACK64 (disabled)"}
- {"dt": "2024-07-11T07:38:44.793393", "lvl": "target", "msg": "[    1.377972] evm: security.SMACK64EXEC (disabled)"}
- {"dt": "2024-07-11T07:38:44.793863", "lvl": "target", "msg": "[    1.378214] evm: security.SMACK64TRANSMUTE (disabled)"}
- {"dt": "2024-07-11T07:38:44.793949", "lvl": "target", "msg": "[    1.378468] evm: security.SMACK64MMAP (disabled)"}
- {"dt": "2024-07-11T07:38:44.794206", "lvl": "target", "msg": "[    1.378716] evm: security.apparmor"}
- {"dt": "2024-07-11T07:38:44.794485", "lvl": "target", "msg": "[    1.378913] evm: security.ima"}
- {"dt": "2024-07-11T07:38:44.794568", "lvl": "target", "msg": "[    1.379092] evm: security.capability"}
- {"dt": "2024-07-11T07:38:44.794886", "lvl": "target", "msg": "[    1.379308] evm: HMAC attrs: 0x1"}
- {"dt": "2024-07-11T07:38:44.795262", "lvl": "target", "msg": "[    1.391555] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-07-11T07:38:44.795570", "lvl": "target", "msg": "[    1.402576] clk: Disabling unused clocks"}
- {"dt": "2024-07-11T07:38:44.796014", "lvl": "target", "msg": "[    1.403186] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-07-11T07:38:44.796098", "lvl": "target", "msg": "[    1.404026] ALSA device list:"}
- {"dt": "2024-07-11T07:38:44.796354", "lvl": "target", "msg": "[    1.404297]   No soundcards found."}
- {"dt": "2024-07-11T07:38:44.797076", "lvl": "target", "msg": "[    1.444322] EXT4-fs (vda): mounted filesystem 6a4cd5c7-4ab5-45d4-8820-40d209833431 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-07-11T07:38:44.797458", "lvl": "target", "msg": "[    1.446014] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-07-11T07:38:44.797546", "lvl": "target", "msg": "[    1.450818] devtmpfs: mounted"}
- {"dt": "2024-07-11T07:38:44.797947", "lvl": "target", "msg": "[    1.524683] Freeing unused kernel memory: 14016K"}
- {"dt": "2024-07-11T07:38:44.798256", "lvl": "target", "msg": "[    1.713034] Checked W+X mappings: passed, no W+X pages found"}
- {"dt": "2024-07-11T07:38:44.798535", "lvl": "target", "msg": "[    1.714822] Run /sbin/init as init process"}
- {"dt": "2024-07-11T07:38:44.798613", "lvl": "target", "msg": "[    1.715265]   with arguments:"}
- {"dt": "2024-07-11T07:38:44.798859", "lvl": "target", "msg": "[    1.715543]     /sbin/init"}
- {"dt": "2024-07-11T07:38:44.799157", "lvl": "target", "msg": "[    1.715991]     verbose"}
- {"dt": "2024-07-11T07:38:44.799233", "lvl": "target", "msg": "[    1.716208]   with environment:"}
- {"dt": "2024-07-11T07:38:44.799664", "lvl": "target", "msg": "[    1.716451]     HOME=/"}
- {"dt": "2024-07-11T07:38:44.799936", "lvl": "target", "msg": "[    1.716659]     TERM=linux"}
- {"dt": "2024-07-11T07:38:44.800362", "lvl": "target", "msg": "[    1.745654] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist."}
- {"dt": "2024-07-11T07:38:44.800651", "lvl": "target", "msg": "[    2.189379] systemd[1]: Inserted module 'autofs4'"}
- {"dt": "2024-07-11T07:38:44.801270", "lvl": "target", "msg": "[    4.482836] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log."}
- {"dt": "2024-07-11T07:38:44.801557", "lvl": "target", "msg": "[    4.484148] device-mapper: uevent: version 1.0.3"}
- {"dt": "2024-07-11T07:38:44.802088", "lvl": "target", "msg": "[    4.487651] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev"}
- {"dt": "2024-07-11T07:38:44.802357", "lvl": "target", "msg": "[    4.649625] fuse: init (API version 7.40)"}
- {"dt": "2024-07-11T07:38:44.802435", "lvl": "target", "msg": "[    4.730386] loop: module loaded"}
- {"dt": "2024-07-11T07:38:44.809479", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDTC command>"}
- {"dt": "2024-07-11T07:38:44.809802", "lvl": "debug", "msg": "Received signal: <ENDTC> command"}
- {"dt": "2024-07-11T07:38:44.851172", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=command RESULT=pass>"}
- {"dt": "2024-07-11T07:38:44.851533", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=command RESULT=pass"}
- {"dt": "2024-07-11T07:38:44.851647", "lvl": "results", "msg": {"definition": "0_commands", "case": "command", "result": "pass", "starttc": ! "752", "endtc": ! "1100"}}
- {"dt": "2024-07-11T07:38:44.856894", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-07-11T07:38:44.857208", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2024-07-11T07:38:44.857305", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-07-11T07:38:44.857376", "lvl": "info", "msg": "Ending test lava.0_commands (1_1.1.3.1), duration 0.26"}
- {"dt": "2024-07-11T07:38:44.857493", "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.26", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2024-07-11T07:38:44.857725", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2024-07-11T07:38:44.873178", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-07-11T07:38:44.873420", "lvl": "debug", "msg": "command: pass\n"}
- {"dt": "2024-07-11T07:38:44.873545", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:44.873649", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:44.873751", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:44.873858", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-07-11T07:38:44.873957", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-07-11T07:38:44.874049", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-07-11T07:38:44.874251", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-07-11T07:38:44.874535", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:38:44.881088", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:38:44.881781", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:38:44.882052", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-07-11T07:38:45.875261", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-07-11T07:38:45.875466", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-07-11T07:38:45.975778", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-07-11T07:38:45.976311", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-07-11T07:38:45.976415", "lvl": "target", "msg": "[?2004hroot@runner-vwmj3eza-project-40964107-concurrent-1:~# poweroff"}
- {"dt": "2024-07-11T07:38:46.076744", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:46.076930", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-07-11T07:38:46.077026", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-07-11T07:38:46.077136", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-07-11T07:38:46.077284", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/kernel"}
- {"dt": "2024-07-11T07:38:46.088075", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs"}
- {"dt": "2024-07-11T07:38:46.246512", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.modules"}
- {"dt": "2024-07-11T07:38:46.266552", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1/deployimages-7xd54dy4/rootfs.overlay-00"}
- {"dt": "2024-07-11T07:38:46.269294", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-128l9yvw-1"}
- {"dt": "2024-07-11T07:38:46.270461", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-07-11T07:38:46.270550", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
