- {"dt": "2025-10-15T07:00:32.750897", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2025.06.dev0075"}
- {"dt": "2025-10-15T07:00:32.751008", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-10-15T07:00:32.751121", "lvl": "info", "msg": "Start time: 2025-10-15 07:00:32.751114+00:00 (UTC)"}
- {"dt": "2025-10-15T07:00:32.752120", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/1026/bzImage exists"}
- {"dt": "2025-10-15T07:00:32.752266", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4.xz exists"}
- {"dt": "2025-10-15T07:00:32.752385", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/1026/modules.tar.xz exists"}
- {"dt": "2025-10-15T07:00:32.752504", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tmp/debian/trixie/i386/ltp/master/ltp.tar.xz exists"}
- {"dt": "2025-10-15T07:00:32.765435", "lvl": "info", "msg": "qemu, installed at version: 10.0.2, host architecture: x86_64"}
- {"dt": "2025-10-15T07:00:32.765691", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2025-10-15T07:00:32.765777", "lvl": "warning", "msg": "Missing image arg for rootfs.overlay-00"}
- {"dt": "2025-10-15T07:00:32.765921", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2025-10-15T07:00:32.765982", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-10-15T07:00:32.766091", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.766166", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.766332", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_"}
- {"dt": "2025-10-15T07:00:32.766469", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin"}
- {"dt": "2025-10-15T07:00:32.766574", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/tests"}
- {"dt": "2025-10-15T07:00:32.766675", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/results"}
- {"dt": "2025-10-15T07:00:32.766763", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-add-keys"}
- {"dt": "2025-10-15T07:00:32.766902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-add-sources"}
- {"dt": "2025-10-15T07:00:32.767038", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-10-15T07:00:32.767172", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-10-15T07:00:32.767309", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-common-functions"}
- {"dt": "2025-10-15T07:00:32.767439", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-10-15T07:00:32.767568", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-install-packages"}
- {"dt": "2025-10-15T07:00:32.767697", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-10-15T07:00:32.767822", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-os-build"}
- {"dt": "2025-10-15T07:00:32.767953", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-10-15T07:00:32.768085", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-10-15T07:00:32.768213", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-target-ip"}
- {"dt": "2025-10-15T07:00:32.768342", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-target-mac"}
- {"dt": "2025-10-15T07:00:32.768470", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-target-storage"}
- {"dt": "2025-10-15T07:00:32.768607", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-case"}
- {"dt": "2025-10-15T07:00:32.768737", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-event"}
- {"dt": "2025-10-15T07:00:32.768970", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-10-15T07:00:32.769142", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-raise"}
- {"dt": "2025-10-15T07:00:32.769287", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-reference"}
- {"dt": "2025-10-15T07:00:32.769429", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-runner"}
- {"dt": "2025-10-15T07:00:32.769561", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-set"}
- {"dt": "2025-10-15T07:00:32.769694", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-test-shell"}
- {"dt": "2025-10-15T07:00:32.769829", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-10-15T07:00:32.769991", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-10-15T07:00:32.770112", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/environment"}
- {"dt": "2025-10-15T07:00:32.770210", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-10-15T07:00:32.770271", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-10-15T07:00:32.770325", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-10-15T07:00:32.770377", "lvl": "debug", "msg": "- LAVA_DISPATCHER_PREFIX=tuxrun-ejxo_ip1-"}
- {"dt": "2025-10-15T07:00:32.770474", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.770533", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-10-15T07:00:32.770596", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.770664", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.770713", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-10-15T07:00:32.770771", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.770834", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.770892", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-10-15T07:00:32.770959", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.771013", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-10-15T07:00:32.771299", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-10-15T07:00:32.771384", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.771454", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.772167", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.772233", "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": "2025-10-15T07:00:32.772350", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.772829", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.772897", "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": "2025-10-15T07:00:32.773017", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.778419", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/0/tests/0_commands test_uuid 1_1.1.3.1"}
- {"dt": "2025-10-15T07:00:32.778691", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.778775", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.01", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/0/tests/0_commands/run.sh"}}}
- {"dt": "2025-10-15T07:00:32.778899", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-10-15T07:00:32.778949", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/lava-overlay-0lx7tc1_/lava-1/0 for stage 0"}
- {"dt": "2025-10-15T07:00:32.779039", "lvl": "debug", "msg": "- 0_commands"}
- {"dt": "2025-10-15T07:00:32.779131", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.779213", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784596", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784688", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784763", "lvl": "debug", "msg": "start: 1.2 deploy-device-env (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784829", "lvl": "debug", "msg": "end: 1.2 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784893", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.784958", "lvl": "debug", "msg": "start: 1.3.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:32.785077", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/1026/bzImage"}
- {"dt": "2025-10-15T07:00:32.785149", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/kernel/bzImage"}
- {"dt": "2025-10-15T07:00:32.785202", "lvl": "debug", "msg": "total size: 11637248 (11\u202FMB)"}
- {"dt": "2025-10-15T07:00:32.785253", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-10-15T07:00:32.794896", "lvl": "info", "msg": "11\u202FMB downloaded in 0.01\u202Fs (1145.45\u202FMB/s)"}
- {"dt": "2025-10-15T07:00:32.795026", "lvl": "debug", "msg": "end: 1.3.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.795096", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.3.1", "duration": "0.01", "result": "pass", "extra": {"label": "kernel", "size": ! "11637248", "sha256sum": "2cdb6b985a36ff7869eff7ca3d9c1c56619c088f789aa0b11f93aa550f8a25a4"}}}
- {"dt": "2025-10-15T07:00:32.795209", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:32.795296", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-10-15T07:00:32.795364", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:32.795487", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4.xz"}
- {"dt": "2025-10-15T07:00:32.795549", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4"}
- {"dt": "2025-10-15T07:00:32.795600", "lvl": "debug", "msg": "total size: 135840540 (129\u202FMB)"}
- {"dt": "2025-10-15T07:00:32.795650", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-10-15T07:00:32.896359", "lvl": "debug", "msg": "progress   1\u202F% (2\u202FMB)"}
- {"dt": "2025-10-15T07:00:33.057085", "lvl": "debug", "msg": "progress   6\u202F% (7\u202FMB)"}
- {"dt": "2025-10-15T07:00:33.245775", "lvl": "debug", "msg": "progress  11\u202F% (14\u202FMB)"}
- {"dt": "2025-10-15T07:00:33.437268", "lvl": "debug", "msg": "progress  16\u202F% (20\u202FMB)"}
- {"dt": "2025-10-15T07:00:33.629838", "lvl": "debug", "msg": "progress  21\u202F% (27\u202FMB)"}
- {"dt": "2025-10-15T07:00:33.823102", "lvl": "debug", "msg": "progress  26\u202F% (33\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.033949", "lvl": "debug", "msg": "progress  31\u202F% (40\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.222219", "lvl": "debug", "msg": "progress  36\u202F% (46\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.404410", "lvl": "debug", "msg": "progress  41\u202F% (53\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.604341", "lvl": "debug", "msg": "progress  46\u202F% (59\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.777576", "lvl": "debug", "msg": "progress  51\u202F% (66\u202FMB)"}
- {"dt": "2025-10-15T07:00:34.961169", "lvl": "debug", "msg": "progress  56\u202F% (72\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.135857", "lvl": "debug", "msg": "progress  61\u202F% (79\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.322150", "lvl": "debug", "msg": "progress  66\u202F% (85\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.482126", "lvl": "debug", "msg": "progress  71\u202F% (92\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.657932", "lvl": "debug", "msg": "progress  76\u202F% (98\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.817086", "lvl": "debug", "msg": "progress  81\u202F% (104\u202FMB)"}
- {"dt": "2025-10-15T07:00:35.993022", "lvl": "debug", "msg": "progress  86\u202F% (111\u202FMB)"}
- {"dt": "2025-10-15T07:00:36.183942", "lvl": "debug", "msg": "progress  91\u202F% (117\u202FMB)"}
- {"dt": "2025-10-15T07:00:36.380676", "lvl": "debug", "msg": "progress  96\u202F% (124\u202FMB)"}
- {"dt": "2025-10-15T07:00:39.689713", "lvl": "info", "msg": "129\u202FMB downloaded in 6.89\u202Fs (18.79\u202FMB/s)"}
- {"dt": "2025-10-15T07:00:39.689967", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:07) [common]"}
- {"dt": "2025-10-15T07:00:39.690086", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "6.89", "result": "pass", "extra": {"label": "rootfs", "size": ! "135840540", "sha256sum": "079fe94d4a1c34571351e9db74d2cdde88d003f51f3b7ad09ff2072e67e21265"}}}
- {"dt": "2025-10-15T07:00:39.690358", "lvl": "debug", "msg": "start: 1.4.2 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:39.690507", "lvl": "debug", "msg": "start: 1.4.2.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-10-15T07:00:39.690784", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/1026/modules.tar.xz"}
- {"dt": "2025-10-15T07:00:39.690911", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.modules/modules.tar"}
- {"dt": "2025-10-15T07:00:39.691015", "lvl": "debug", "msg": "total size: 92472 (0\u202FMB)"}
- {"dt": "2025-10-15T07:00:39.691110", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-10-15T07:00:39.695896", "lvl": "info", "msg": "0\u202FMB downloaded in 0.00\u202Fs (18.11\u202FMB/s)"}
- {"dt": "2025-10-15T07:00:39.696133", "lvl": "debug", "msg": "end: 1.4.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:39.696223", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.2.1", "duration": "0.01", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "92472", "sha256sum": "f8add7089f75ec37508ce8ec8c1077166e181c64d5db78746883a3b5c9bfd895"}}}
- {"dt": "2025-10-15T07:00:39.696389", "lvl": "debug", "msg": "end: 1.4.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:39.696493", "lvl": "debug", "msg": "start: 1.4.3 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:39.696594", "lvl": "debug", "msg": "start: 1.4.3.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-10-15T07:00:39.696771", "lvl": "info", "msg": "downloading file:///home/anders/src/tmp/debian/trixie/i386/ltp/master/ltp.tar.xz"}
- {"dt": "2025-10-15T07:00:39.696862", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.overlay-00/ltp.tar"}
- {"dt": "2025-10-15T07:00:39.696941", "lvl": "debug", "msg": "total size: 37245772 (35\u202FMB)"}
- {"dt": "2025-10-15T07:00:39.697005", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-10-15T07:00:40.010940", "lvl": "info", "msg": "35\u202FMB downloaded in 0.31\u202Fs (113.13\u202FMB/s)"}
- {"dt": "2025-10-15T07:00:40.011205", "lvl": "debug", "msg": "end: 1.4.3.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:40.011297", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.3.1", "duration": "0.31", "result": "pass", "extra": {"label": "rootfs.overlay-00", "size": ! "37245772", "sha256sum": "7f1d4a501356a95c1b8307c7ae25d0249205042c7b3df182b6a0cef97c7b5a09"}}}
- {"dt": "2025-10-15T07:00:40.011463", "lvl": "debug", "msg": "end: 1.4.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:40.011565", "lvl": "debug", "msg": "start: 1.4.4 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:40.011638", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4'"}
- {"dt": "2025-10-15T07:00:41.075278", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-10-15T07:00:41.076119", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/compress-overlay-pn35s_vb/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-10-15T07:00:41.080713", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2025-10-15T07:00:41.084259", "lvl": "debug", "msg": "- rootfs.overlay-00: '/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.overlay-00/ltp.tar' to '/'"}
- {"dt": "2025-10-15T07:00:42.683691", "lvl": "debug", "msg": "end: 1.4.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2025-10-15T07:00:42.684031", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:10) [common]"}
- {"dt": "2025-10-15T07:00:42.684133", "lvl": "debug", "msg": "start: 1.5 apply-overlay-guest (timeout 00:14:50) [common]"}
- {"dt": "2025-10-15T07:00:42.684215", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-10-15T07:00:42.684295", "lvl": "debug", "msg": "end: 1.5 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:42.684380", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:10) [common]"}
- {"dt": "2025-10-15T07:00:42.684456", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:42.684538", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:42.684614", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:42.684871", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-i386 -cpu coreduo -machine q35 -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/kernel/bzImage -append \"console=ttyS0,115200 rootwait root=/dev/sda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4,if=ide,format=raw"}
- {"dt": "2025-10-15T07:00:42.688449", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-10-15T07:00:42.689056", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:42.689174", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "10.0.2", "host_arch": "x86_64", "job_arch": "i386"}}}
- {"dt": "2025-10-15T07:00:42.689346", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:42.689456", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:42.689562", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-10-15T07:00:42.689646", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2025-10-15T07:00:42.774102", "lvl": "target", "msg": "c[?7l[2J[0mSeaBIOS (version 1.16.3-debian-1.16.3-2)"}
- {"dt": "2025-10-15T07:00:42.777594", "lvl": "target", "msg": "Booting from ROM..."}
- {"dt": "2025-10-15T07:00:42.791447", "lvl": "target", "msg": "early console in setup code"}
- {"dt": "2025-10-15T07:00:43.345740", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:19:59) [common]"}
- {"dt": "2025-10-15T07:00:43.345927", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-10-15T07:00:43.346024", "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', 'U-Boot SPL 20[0-9][0-9]']"}
- {"dt": "2025-10-15T07:00:43.346115", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-10-15T07:00:43.346183", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-10-15T07:00:43.346254", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-10-15T07:00:43.346347", "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", "U-Boot SPL 20[0-9][0-9]", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2025-10-15T07:00:43.346527", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:59)"}
- {"dt": "2025-10-15T07:00:43.346601", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2025-10-15T07:00:43.347264", "lvl": "target", "msg": "c[?7l[2J<5>[    0.000000] Linux version 6.18.0-rc1 (tuxmake@tuxmake) (i686-linux-gnu-gcc (Debian 13.3.0-16) 13.3.0, GNU ld (GNU Binutils for Debian) 2.44) #1 SMP PREEMPT_DYNAMIC @1760301756"}
- {"dt": "2025-10-15T07:00:43.347361", "lvl": "target", "msg": "<6>[    0.000000] BIOS-provided physical RAM map:"}
- {"dt": "2025-10-15T07:00:43.347445", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable"}
- {"dt": "2025-10-15T07:00:43.347520", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved"}
- {"dt": "2025-10-15T07:00:43.347592", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved"}
- {"dt": "2025-10-15T07:00:43.347656", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007ffdefff] usable"}
- {"dt": "2025-10-15T07:00:43.347721", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x000000007ffdf000-0x000000007fffffff] reserved"}
- {"dt": "2025-10-15T07:00:43.347790", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved"}
- {"dt": "2025-10-15T07:00:43.347860", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved"}
- {"dt": "2025-10-15T07:00:43.347928", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved"}
- {"dt": "2025-10-15T07:00:43.348021", "lvl": "target", "msg": "<6>[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000017fffffff] usable"}
- {"dt": "2025-10-15T07:00:43.348091", "lvl": "target", "msg": "<5>[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!"}
- {"dt": "2025-10-15T07:00:43.348160", "lvl": "target", "msg": "<6>[    0.000000] APIC: Static calls initialized"}
- {"dt": "2025-10-15T07:00:43.348227", "lvl": "target", "msg": "<6>[    0.000000] SMBIOS 2.8 present."}
- {"dt": "2025-10-15T07:00:43.348290", "lvl": "target", "msg": "<6>[    0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.3-debian-1.16.3-2 04/01/2014"}
- {"dt": "2025-10-15T07:00:43.348369", "lvl": "target", "msg": "<6>[    0.000000] DMI: Memory slots populated: 1/1"}
- {"dt": "2025-10-15T07:00:43.348440", "lvl": "target", "msg": "<6>[    0.000000] tsc: Fast TSC calibration using PIT"}
- {"dt": "2025-10-15T07:00:43.348494", "lvl": "target", "msg": "<6>[    0.000000] tsc: Detected 3393.590 MHz processor"}
- {"dt": "2025-10-15T07:00:43.348578", "lvl": "target", "msg": "<7>[    0.005817] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved"}
- {"dt": "2025-10-15T07:00:43.348691", "lvl": "target", "msg": "<7>[    0.006110] e820: remove [mem 0x000a0000-0x000fffff] usable"}
- {"dt": "2025-10-15T07:00:43.348840", "lvl": "target", "msg": "<6>[    0.006342] last_pfn = 0x7ffdf max_arch_pfn = 0x100000"}
- {"dt": "2025-10-15T07:00:43.349029", "lvl": "target", "msg": "<6>[    0.007012] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs"}
- {"dt": "2025-10-15T07:00:43.349174", "lvl": "target", "msg": "<6>[    0.007073] x86/PAT: PAT not supported by the CPU."}
- {"dt": "2025-10-15T07:00:43.349356", "lvl": "target", "msg": "<6>[    0.007228] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  "}
- {"dt": "2025-10-15T07:00:43.349472", "lvl": "target", "msg": "<4>[    0.007771] Warning only 883MB will be used."}
- {"dt": "2025-10-15T07:00:43.349584", "lvl": "target", "msg": "<4>[    0.007785] Use a HIGHMEM enabled kernel."}
- {"dt": "2025-10-15T07:00:43.349734", "lvl": "target", "msg": "<6>[    0.017787] found SMP MP-table at [mem 0x000f5480-0x000f548f]"}
- {"dt": "2025-10-15T07:00:43.349883", "lvl": "target", "msg": "<7>[    0.018832] initial memory mapped: [mem 0x00000000-0x10bfffff]"}
- {"dt": "2025-10-15T07:00:43.350133", "lvl": "target", "msg": "<6>[    0.021187] ACPI: Early table checksum verification disabled"}
- {"dt": "2025-10-15T07:00:43.350197", "lvl": "target", "msg": "<6>[    0.021801] ACPI: RSDP 0x00000000000F52A0 000014 (v00 BOCHS )"}
- {"dt": "2025-10-15T07:00:43.350409", "lvl": "target", "msg": "<6>[    0.022080] ACPI: RSDT 0x000000007FFE239B 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.350621", "lvl": "target", "msg": "<6>[    0.022771] ACPI: FACP 0x000000007FFE218B 0000F4 (v03 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.350837", "lvl": "target", "msg": "<6>[    0.023473] ACPI: DSDT 0x000000007FFE0040 00214B (v01 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.350962", "lvl": "target", "msg": "<6>[    0.023544] ACPI: FACS 0x000000007FFE0000 000040"}
- {"dt": "2025-10-15T07:00:43.351177", "lvl": "target", "msg": "<6>[    0.023588] ACPI: APIC 0x000000007FFE227F 000080 (v03 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.351399", "lvl": "target", "msg": "<6>[    0.023614] ACPI: HPET 0x000000007FFE22FF 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.351615", "lvl": "target", "msg": "<6>[    0.023630] ACPI: MCFG 0x000000007FFE2337 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.351844", "lvl": "target", "msg": "<6>[    0.023645] ACPI: WAET 0x000000007FFE2373 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)"}
- {"dt": "2025-10-15T07:00:43.352025", "lvl": "target", "msg": "<6>[    0.023747] ACPI: Reserving FACP table memory at [mem 0x7ffe218b-0x7ffe227e]"}
- {"dt": "2025-10-15T07:00:43.352208", "lvl": "target", "msg": "<6>[    0.023772] ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe218a]"}
- {"dt": "2025-10-15T07:00:43.352397", "lvl": "target", "msg": "<6>[    0.023778] ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]"}
- {"dt": "2025-10-15T07:00:43.352588", "lvl": "target", "msg": "<6>[    0.023783] ACPI: Reserving APIC table memory at [mem 0x7ffe227f-0x7ffe22fe]"}
- {"dt": "2025-10-15T07:00:43.352768", "lvl": "target", "msg": "<6>[    0.023788] ACPI: Reserving HPET table memory at [mem 0x7ffe22ff-0x7ffe2336]"}
- {"dt": "2025-10-15T07:00:43.352959", "lvl": "target", "msg": "<6>[    0.023793] ACPI: Reserving MCFG table memory at [mem 0x7ffe2337-0x7ffe2372]"}
- {"dt": "2025-10-15T07:00:43.353162", "lvl": "target", "msg": "<6>[    0.023798] ACPI: Reserving WAET table memory at [mem 0x7ffe2373-0x7ffe239a]"}
- {"dt": "2025-10-15T07:00:43.353256", "lvl": "target", "msg": "<5>[    0.024934] 883MB LOWMEM available."}
- {"dt": "2025-10-15T07:00:43.353363", "lvl": "target", "msg": "<6>[    0.024948]   mapped low ram: 0 - 373fe000"}
- {"dt": "2025-10-15T07:00:43.353467", "lvl": "target", "msg": "<6>[    0.024959]   low ram: 0 - 373fe000"}
- {"dt": "2025-10-15T07:00:43.353538", "lvl": "target", "msg": "<6>[    0.025214] Zone ranges:"}
- {"dt": "2025-10-15T07:00:43.353713", "lvl": "target", "msg": "<6>[    0.025236]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]"}
- {"dt": "2025-10-15T07:00:43.353877", "lvl": "target", "msg": "<6>[    0.025301]   Normal   [mem 0x0000000001000000-0x00000000373fdfff]"}
- {"dt": "2025-10-15T07:00:43.354003", "lvl": "target", "msg": "<6>[    0.025313] Movable zone start for each node"}
- {"dt": "2025-10-15T07:00:43.354100", "lvl": "target", "msg": "<6>[    0.025335] Early memory node ranges"}
- {"dt": "2025-10-15T07:00:43.354270", "lvl": "target", "msg": "<6>[    0.025371]   node   0: [mem 0x0000000000001000-0x000000000009efff]"}
- {"dt": "2025-10-15T07:00:43.354545", "lvl": "target", "msg": "<6>[    0.025408]   node   0: [mem 0x0000000000100000-0x00000000373fdfff]"}
- {"dt": "2025-10-15T07:00:43.354612", "lvl": "target", "msg": "<6>[    0.025527] Initmem setup node 0 [mem 0x0000000000001000-0x00000000373fdfff]"}
- {"dt": "2025-10-15T07:00:43.354756", "lvl": "target", "msg": "<6>[    0.026604] On node 0, zone DMA: 1 pages in unavailable ranges"}
- {"dt": "2025-10-15T07:00:43.354905", "lvl": "target", "msg": "<6>[    0.026868] On node 0, zone DMA: 97 pages in unavailable ranges"}
- {"dt": "2025-10-15T07:00:43.355068", "lvl": "target", "msg": "<6>[    0.035621] On node 0, zone Normal: 2 pages in unavailable ranges"}
- {"dt": "2025-10-15T07:00:43.355199", "lvl": "target", "msg": "<6>[    0.036088] ACPI: PM-Timer IO Port: 0x608"}
- {"dt": "2025-10-15T07:00:43.355391", "lvl": "target", "msg": "<6>[    0.036474] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])"}
- {"dt": "2025-10-15T07:00:43.355605", "lvl": "target", "msg": "<6>[    0.036789] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23"}
- {"dt": "2025-10-15T07:00:43.355793", "lvl": "target", "msg": "<6>[    0.036879] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)"}
- {"dt": "2025-10-15T07:00:43.356080", "lvl": "target", "msg": "<6>[    0.037107] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)"}
- {"dt": "2025-10-15T07:00:43.356165", "lvl": "target", "msg": "<6>[    0.037141] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)"}
- {"dt": "2025-10-15T07:00:43.356371", "lvl": "target", "msg": "<6>[    0.037222] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)"}
- {"dt": "2025-10-15T07:00:43.356560", "lvl": "target", "msg": "<6>[    0.037229] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)"}
- {"dt": "2025-10-15T07:00:43.356745", "lvl": "target", "msg": "<6>[    0.037376] ACPI: Using ACPI (MADT) for SMP configuration information"}
- {"dt": "2025-10-15T07:00:43.356871", "lvl": "target", "msg": "<6>[    0.037618] CPU topo: Max. logical packages:   1"}
- {"dt": "2025-10-15T07:00:43.357019", "lvl": "target", "msg": "<6>[    0.037630] CPU topo: Max. logical dies:       1"}
- {"dt": "2025-10-15T07:00:43.357357", "lvl": "target", "msg": "<6>[    0.037639] CPU topo: Max. dies per package:   1"}
- {"dt": "2025-10-15T07:00:43.357416", "lvl": "target", "msg": "<6>[    0.037659] CPU topo: Max. threads per core:   1"}
- {"dt": "2025-10-15T07:00:43.357477", "lvl": "target", "msg": "<6>[    0.037825] CPU topo: Num. cores per package:     2"}
- {"dt": "2025-10-15T07:00:43.357593", "lvl": "target", "msg": "<6>[    0.037841] CPU topo: Num. threads per package:   2"}
- {"dt": "2025-10-15T07:00:43.357975", "lvl": "target", "msg": "<6>[    0.037851] CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs"}
- {"dt": "2025-10-15T07:00:43.358051", "lvl": "target", "msg": "<6>[    0.038576] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-10-15T07:00:43.358193", "lvl": "target", "msg": "<6>[    0.038613] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]"}
- {"dt": "2025-10-15T07:00:43.358408", "lvl": "target", "msg": "<6>[    0.038620] PM: hibernation: Registered nosave memory: [mem 0x7ffdf000-0xffffffff]"}
- {"dt": "2025-10-15T07:00:43.358584", "lvl": "target", "msg": "<6>[    0.038769] [mem 0xc0000000-0xfed1bfff] available for PCI devices"}
- {"dt": "2025-10-15T07:00:43.358744", "lvl": "target", "msg": "<6>[    0.038821] Booting paravirtualized kernel on bare hardware"}
- {"dt": "2025-10-15T07:00:43.359032", "lvl": "target", "msg": "<6>[    0.039219] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns"}
- {"dt": "2025-10-15T07:00:43.359390", "lvl": "target", "msg": "<6>[    0.039621] setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1"}
- {"dt": "2025-10-15T07:00:43.359461", "lvl": "target", "msg": "<6>[    0.040783] percpu: Embedded 29 pages/cpu s88332 r0 d30452 u118784"}
- {"dt": "2025-10-15T07:00:43.359554", "lvl": "target", "msg": "<7>[    0.041135] pcpu-alloc: s88332 r0 d30452 u118784 alloc=29*4096"}
- {"dt": "2025-10-15T07:00:43.359667", "lvl": "target", "msg": "<7>[    0.041187] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-10-15T07:00:43.360035", "lvl": "target", "msg": "<5>[    0.042331] Kernel command line: console=ttyS0,115200 rootwait root=/dev/sda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2025-10-15T07:00:43.360262", "lvl": "target", "msg": "<5>[    0.043586] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-10-15T07:00:43.360469", "lvl": "target", "msg": "<6>[    0.043775] printk: log buffer data + meta data: 262144 + 819200 = 1081344 bytes"}
- {"dt": "2025-10-15T07:00:43.360686", "lvl": "target", "msg": "<6>[    0.044249] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.360896", "lvl": "target", "msg": "<6>[    0.044467] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.361087", "lvl": "target", "msg": "<6>[    0.045957] Built 1 zonelists, mobility grouping on.  Total pages: 226204"}
- {"dt": "2025-10-15T07:00:43.361297", "lvl": "target", "msg": "<6>[    0.046200] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-10-15T07:00:43.361538", "lvl": "target", "msg": "<6>[    0.049654] Checking if this processor honours the WP bit even in supervisor mode...Ok."}
- {"dt": "2025-10-15T07:00:43.361728", "lvl": "target", "msg": "<6>[    0.053998] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-10-15T07:00:43.361831", "lvl": "target", "msg": "<6>[    0.061058] Dynamic Preempt: voluntary"}
- {"dt": "2025-10-15T07:00:43.362027", "lvl": "target", "msg": "<6>[    0.063839] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2025-10-15T07:00:43.362147", "lvl": "target", "msg": "<6>[    0.063848] rcu: \tRCU event tracing is enabled."}
- {"dt": "2025-10-15T07:00:43.362343", "lvl": "target", "msg": "<6>[    0.063871] rcu: \tRCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2."}
- {"dt": "2025-10-15T07:00:43.362478", "lvl": "target", "msg": "<6>[    0.063994] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2025-10-15T07:00:43.362621", "lvl": "target", "msg": "<6>[    0.064001] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2025-10-15T07:00:43.362848", "lvl": "target", "msg": "<6>[    0.064081] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies."}
- {"dt": "2025-10-15T07:00:43.363037", "lvl": "target", "msg": "<6>[    0.064107] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-10-15T07:00:43.363274", "lvl": "target", "msg": "<6>[    0.065053] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2025-10-15T07:00:43.363527", "lvl": "target", "msg": "<6>[    0.065077] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2025-10-15T07:00:43.363675", "lvl": "target", "msg": "<6>[    0.089561] NR_IRQS: 2304, nr_irqs: 440, preallocated irqs: 16"}
- {"dt": "2025-10-15T07:00:43.364043", "lvl": "target", "msg": "<6>[    0.094626] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-10-15T07:00:43.364118", "lvl": "target", "msg": "<6>[    0.103014] Console: colour VGA+ 80x25"}
- {"dt": "2025-10-15T07:00:43.364196", "lvl": "target", "msg": "<6>[    0.104899] printk: legacy console [ttyS0] enabled"}
- {"dt": "2025-10-15T07:00:43.365219", "lvl": "target", "msg": "<6>[    0.125533] ACPI: Core revision 20250807"}
- {"dt": "2025-10-15T07:00:43.374265", "lvl": "target", "msg": "<6>[    0.134567] APIC: Switch to symmetric I/O mode setup"}
- {"dt": "2025-10-15T07:00:43.378244", "lvl": "target", "msg": "<6>[    0.138531] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1"}
- {"dt": "2025-10-15T07:00:43.383434", "lvl": "target", "msg": "<6>[    0.143764] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x30eaa752e17, max_idle_ns: 440795303210 ns"}
- {"dt": "2025-10-15T07:00:43.384478", "lvl": "target", "msg": "<6>[    0.144638] Calibrating delay loop (skipped), value calculated using timer frequency.. 6787.18 BogoMIPS (lpj=3393590)"}
- {"dt": "2025-10-15T07:00:43.387796", "lvl": "target", "msg": "<6>[    0.149167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0"}
- {"dt": "2025-10-15T07:00:43.387898", "lvl": "target", "msg": "<6>[    0.149329] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0"}
- {"dt": "2025-10-15T07:00:43.388466", "lvl": "target", "msg": "<6>[    0.149634] mitigations: Enabled attack vectors: user_kernel, user_user, SMT mitigations: auto"}
- {"dt": "2025-10-15T07:00:43.388958", "lvl": "target", "msg": "<6>[    0.150205] Spectre V2 : Mitigation: Retpolines"}
- {"dt": "2025-10-15T07:00:43.389024", "lvl": "target", "msg": "<6>[    0.150446] ITS: Vulnerable"}
- {"dt": "2025-10-15T07:00:43.389231", "lvl": "target", "msg": "<6>[    0.150567] MDS: Vulnerable: Clear CPU buffers attempted, no microcode"}
- {"dt": "2025-10-15T07:00:43.389531", "lvl": "target", "msg": "<6>[    0.150845] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization"}
- {"dt": "2025-10-15T07:00:43.389827", "lvl": "target", "msg": "<6>[    0.151128] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT"}
- {"dt": "2025-10-15T07:00:43.390117", "lvl": "target", "msg": "<4>[    0.151490] L1TF: Kernel not compiled for PAE. No mitigation for L1TF"}
- {"dt": "2025-10-15T07:00:43.396994", "lvl": "target", "msg": "<6>[    0.153924] x86/fpu: x87 FPU will use FXSAVE"}
- {"dt": "2025-10-15T07:00:43.408952", "lvl": "target", "msg": "<6>[    0.168222] Freeing SMP alternatives memory: 52K"}
- {"dt": "2025-10-15T07:00:43.409287", "lvl": "target", "msg": "<6>[    0.168681] pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-10-15T07:00:43.415290", "lvl": "target", "msg": "<6>[    0.174114] LSM: initializing lsm=capability,selinux"}
- {"dt": "2025-10-15T07:00:43.416284", "lvl": "target", "msg": "<6>[    0.175678] SELinux:  Initializing."}
- {"dt": "2025-10-15T07:00:43.423165", "lvl": "target", "msg": "<6>[    0.180527] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.423347", "lvl": "target", "msg": "<6>[    0.180783] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.554464", "lvl": "target", "msg": "<6>[    0.310383] smpboot: CPU0: Genuine Intel(R) CPU           T2600  @ 2.16GHz (family: 0x6, model: 0xe, stepping: 0x8)"}
- {"dt": "2025-10-15T07:00:43.565318", "lvl": "target", "msg": "<6>[    0.320592] Performance Events: unsupported CPU family 6 model 14 no PMU driver, software events only."}
- {"dt": "2025-10-15T07:00:43.566144", "lvl": "target", "msg": "<6>[    0.321495] signal: max sigframe size: 1440"}
- {"dt": "2025-10-15T07:00:43.567452", "lvl": "target", "msg": "<6>[    0.322861] rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-10-15T07:00:43.567539", "lvl": "target", "msg": "<6>[    0.323019] rcu: \tMax phase no-delay instances is 400."}
- {"dt": "2025-10-15T07:00:43.568814", "lvl": "target", "msg": "<6>[    0.324138] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2025-10-15T07:00:43.572507", "lvl": "target", "msg": "<6>[    0.327850] smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-10-15T07:00:43.578770", "lvl": "target", "msg": "<6>[    0.334175] smpboot: x86: Booting SMP configuration:"}
- {"dt": "2025-10-15T07:00:43.591486", "lvl": "target", "msg": "<6>[    0.334334] .... node  #0, CPUs:      #1"}
- {"dt": "2025-10-15T07:00:43.591571", "lvl": "target", "msg": "<6>[    0.344842] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-10-15T07:00:43.591965", "lvl": "target", "msg": "<6>[    0.345228] smpboot: Total of 2 processors activated (13574.36 BogoMIPS)"}
- {"dt": "2025-10-15T07:00:43.598771", "lvl": "target", "msg": "<6>[    0.352048] Memory: 869060K/904816K available (15304K kernel code, 2138K rwdata, 5264K rodata, 876K init, 500K bss, 33240K reserved, 0K cma-reserved)"}
- {"dt": "2025-10-15T07:00:43.608444", "lvl": "target", "msg": "<6>[    0.361421] devtmpfs: initialized"}
- {"dt": "2025-10-15T07:00:43.619680", "lvl": "target", "msg": "<6>[    0.372893] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns"}
- {"dt": "2025-10-15T07:00:43.621062", "lvl": "target", "msg": "<6>[    0.373839] posixtimers hash table entries: 1024 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.621448", "lvl": "target", "msg": "<6>[    0.374259] futex hash table entries: 512 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear)."}
- {"dt": "2025-10-15T07:00:43.625271", "lvl": "target", "msg": "<6>[    0.377692] PM: RTC time: 07:00:42, date: 2025-10-15"}
- {"dt": "2025-10-15T07:00:43.631071", "lvl": "target", "msg": "<6>[    0.383907] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-10-15T07:00:43.634366", "lvl": "target", "msg": "<6>[    0.387208] audit: initializing netlink subsys (disabled)"}
- {"dt": "2025-10-15T07:00:43.636407", "lvl": "target", "msg": "<5>[    0.389671] audit: type=2000 audit(1760511642.255:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2025-10-15T07:00:43.641127", "lvl": "target", "msg": "<6>[    0.393881] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-10-15T07:00:43.641244", "lvl": "target", "msg": "<6>[    0.394460] cpuidle: using governor menu"}
- {"dt": "2025-10-15T07:00:43.645577", "lvl": "target", "msg": "<6>[    0.398574] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]"}
- {"dt": "2025-10-15T07:00:43.646406", "lvl": "target", "msg": "<6>[    0.399776] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry"}
- {"dt": "2025-10-15T07:00:43.646671", "lvl": "target", "msg": "<6>[    0.400101] PCI: Using ECAM for extended config space"}
- {"dt": "2025-10-15T07:00:43.647089", "lvl": "target", "msg": "<6>[    0.400511] PCI: Using configuration type 1 for base access"}
- {"dt": "2025-10-15T07:00:43.647973", "lvl": "target", "msg": "<4>[    0.401333] mtrr: your CPUs had inconsistent fixed MTRR settings"}
- {"dt": "2025-10-15T07:00:43.648195", "lvl": "target", "msg": "<4>[    0.401423] mtrr: your CPUs had inconsistent variable MTRR settings"}
- {"dt": "2025-10-15T07:00:43.648509", "lvl": "target", "msg": "<4>[    0.401723] mtrr: your CPUs had inconsistent MTRRdefType settings"}
- {"dt": "2025-10-15T07:00:43.648986", "lvl": "target", "msg": "<6>[    0.402027] mtrr: probably your BIOS does not setup all CPUs."}
- {"dt": "2025-10-15T07:00:43.649078", "lvl": "target", "msg": "<6>[    0.402407] mtrr: corrected configuration."}
- {"dt": "2025-10-15T07:00:43.651484", "lvl": "target", "msg": "<6>[    0.404824] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible."}
- {"dt": "2025-10-15T07:00:43.654647", "lvl": "target", "msg": "<6>[    0.408003] HugeTLB: registered 4.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2025-10-15T07:00:43.654852", "lvl": "target", "msg": "<6>[    0.408207] HugeTLB: 0 KiB vmemmap can be freed for a 4.00 MiB page"}
- {"dt": "2025-10-15T07:00:43.657635", "lvl": "target", "msg": "<6>[    0.410995] ACPI: Added _OSI(Module Device)"}
- {"dt": "2025-10-15T07:00:43.657753", "lvl": "target", "msg": "<6>[    0.411141] ACPI: Added _OSI(Processor Device)"}
- {"dt": "2025-10-15T07:00:43.657945", "lvl": "target", "msg": "<6>[    0.411314] ACPI: Added _OSI(Processor Aggregator Device)"}
- {"dt": "2025-10-15T07:00:43.681923", "lvl": "target", "msg": "<6>[    0.435230] ACPI: 1 ACPI AML tables successfully acquired and loaded"}
- {"dt": "2025-10-15T07:00:43.703161", "lvl": "target", "msg": "<6>[    0.455509] ACPI: Interpreter enabled"}
- {"dt": "2025-10-15T07:00:43.704451", "lvl": "target", "msg": "<6>[    0.456814] ACPI: PM: (supports S0 S3 S4 S5)"}
- {"dt": "2025-10-15T07:00:43.704598", "lvl": "target", "msg": "<6>[    0.457018] ACPI: Using IOAPIC for interrupt routing"}
- {"dt": "2025-10-15T07:00:43.706743", "lvl": "target", "msg": "<6>[    0.458817] PCI: Using host bridge windows from ACPI; if necessary, use \"pci=nocrs\" and report a bug"}
- {"dt": "2025-10-15T07:00:43.706919", "lvl": "target", "msg": "<6>[    0.459412] PCI: Using E820 reservations for host bridge windows"}
- {"dt": "2025-10-15T07:00:43.708579", "lvl": "target", "msg": "<6>[    0.460975] ACPI: Enabled 2 GPEs in block 00 to 3F"}
- {"dt": "2025-10-15T07:00:43.740046", "lvl": "target", "msg": "<6>[    0.492341] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])"}
- {"dt": "2025-10-15T07:00:43.740633", "lvl": "target", "msg": "<6>[    0.492900] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]"}
- {"dt": "2025-10-15T07:00:43.742178", "lvl": "target", "msg": "<6>[    0.494557] acpi PNP0A08:00: _OSC: platform does not support [LTR]"}
- {"dt": "2025-10-15T07:00:43.743410", "lvl": "target", "msg": "<6>[    0.495791] acpi PNP0A08:00: _OSC: OS now controls [PME PCIeCapability]"}
- {"dt": "2025-10-15T07:00:43.744497", "lvl": "target", "msg": "<4>[    0.496870] acpi resource window ([0x180000000-0x97fffffff] ignored, not CPU addressable)"}
- {"dt": "2025-10-15T07:00:43.746903", "lvl": "target", "msg": "<6>[    0.499279] PCI host bridge to bus 0000:00"}
- {"dt": "2025-10-15T07:00:43.747245", "lvl": "target", "msg": "<6>[    0.499628] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]"}
- {"dt": "2025-10-15T07:00:43.747623", "lvl": "target", "msg": "<6>[    0.499881] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]"}
- {"dt": "2025-10-15T07:00:43.747720", "lvl": "target", "msg": "<6>[    0.500094] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]"}
- {"dt": "2025-10-15T07:00:43.747917", "lvl": "target", "msg": "<6>[    0.500277] pci_bus 0000:00: root bus resource [mem 0x80000000-0xafffffff window]"}
- {"dt": "2025-10-15T07:00:43.748175", "lvl": "target", "msg": "<6>[    0.500399] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]"}
- {"dt": "2025-10-15T07:00:43.748498", "lvl": "target", "msg": "<6>[    0.500745] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-10-15T07:00:43.750286", "lvl": "target", "msg": "<6>[    0.502655] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2025-10-15T07:00:43.754358", "lvl": "target", "msg": "<6>[    0.506745] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint"}
- {"dt": "2025-10-15T07:00:43.755462", "lvl": "target", "msg": "<6>[    0.507625] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]"}
- {"dt": "2025-10-15T07:00:43.755616", "lvl": "target", "msg": "<6>[    0.507895] pci 0000:00:01.0: BAR 2 [mem 0xfebf0000-0xfebf0fff]"}
- {"dt": "2025-10-15T07:00:43.755969", "lvl": "target", "msg": "<6>[    0.508063] pci 0000:00:01.0: ROM [mem 0xfebe0000-0xfebeffff pref]"}
- {"dt": "2025-10-15T07:00:43.756220", "lvl": "target", "msg": "<6>[    0.508554] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]"}
- {"dt": "2025-10-15T07:00:43.757007", "lvl": "target", "msg": "<6>[    0.509420] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint"}
- {"dt": "2025-10-15T07:00:43.757573", "lvl": "target", "msg": "<6>[    0.509989] pci 0000:00:1f.0: quirk: [io  0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO"}
- {"dt": "2025-10-15T07:00:43.758429", "lvl": "target", "msg": "<6>[    0.510830] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint"}
- {"dt": "2025-10-15T07:00:43.759364", "lvl": "target", "msg": "<6>[    0.511740] pci 0000:00:1f.2: BAR 4 [io  0xc040-0xc05f]"}
- {"dt": "2025-10-15T07:00:43.759448", "lvl": "target", "msg": "<6>[    0.511909] pci 0000:00:1f.2: BAR 5 [mem 0xfebf1000-0xfebf1fff]"}
- {"dt": "2025-10-15T07:00:43.760301", "lvl": "target", "msg": "<6>[    0.512661] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint"}
- {"dt": "2025-10-15T07:00:43.760940", "lvl": "target", "msg": "<6>[    0.513298] pci 0000:00:1f.3: BAR 4 [io  0x0700-0x073f]"}
- {"dt": "2025-10-15T07:00:43.761705", "lvl": "target", "msg": "<7>[    0.513991] pci_bus 0000:00: on NUMA node 0"}
- {"dt": "2025-10-15T07:00:43.764628", "lvl": "target", "msg": "<6>[    0.517026] ACPI: PCI: Interrupt link LNKA configured for IRQ 10"}
- {"dt": "2025-10-15T07:00:43.765550", "lvl": "target", "msg": "<6>[    0.517943] ACPI: PCI: Interrupt link LNKB configured for IRQ 10"}
- {"dt": "2025-10-15T07:00:43.766216", "lvl": "target", "msg": "<6>[    0.518638] ACPI: PCI: Interrupt link LNKC configured for IRQ 11"}
- {"dt": "2025-10-15T07:00:43.766877", "lvl": "target", "msg": "<6>[    0.519292] ACPI: PCI: Interrupt link LNKD configured for IRQ 11"}
- {"dt": "2025-10-15T07:00:43.767627", "lvl": "target", "msg": "<6>[    0.519885] ACPI: PCI: Interrupt link LNKE configured for IRQ 10"}
- {"dt": "2025-10-15T07:00:43.768320", "lvl": "target", "msg": "<6>[    0.520732] ACPI: PCI: Interrupt link LNKF configured for IRQ 10"}
- {"dt": "2025-10-15T07:00:43.769013", "lvl": "target", "msg": "<6>[    0.521435] ACPI: PCI: Interrupt link LNKG configured for IRQ 11"}
- {"dt": "2025-10-15T07:00:43.769681", "lvl": "target", "msg": "<6>[    0.522104] ACPI: PCI: Interrupt link LNKH configured for IRQ 11"}
- {"dt": "2025-10-15T07:00:43.769966", "lvl": "target", "msg": "<6>[    0.522436] ACPI: PCI: Interrupt link GSIA configured for IRQ 16"}
- {"dt": "2025-10-15T07:00:43.770248", "lvl": "target", "msg": "<6>[    0.522681] ACPI: PCI: Interrupt link GSIB configured for IRQ 17"}
- {"dt": "2025-10-15T07:00:43.770421", "lvl": "target", "msg": "<6>[    0.522909] ACPI: PCI: Interrupt link GSIC configured for IRQ 18"}
- {"dt": "2025-10-15T07:00:43.770719", "lvl": "target", "msg": "<6>[    0.523148] ACPI: PCI: Interrupt link GSID configured for IRQ 19"}
- {"dt": "2025-10-15T07:00:43.770942", "lvl": "target", "msg": "<6>[    0.523404] ACPI: PCI: Interrupt link GSIE configured for IRQ 20"}
- {"dt": "2025-10-15T07:00:43.771257", "lvl": "target", "msg": "<6>[    0.523659] ACPI: PCI: Interrupt link GSIF configured for IRQ 21"}
- {"dt": "2025-10-15T07:00:43.771424", "lvl": "target", "msg": "<6>[    0.523880] ACPI: PCI: Interrupt link GSIG configured for IRQ 22"}
- {"dt": "2025-10-15T07:00:43.771662", "lvl": "target", "msg": "<6>[    0.524137] ACPI: PCI: Interrupt link GSIH configured for IRQ 23"}
- {"dt": "2025-10-15T07:00:43.775264", "lvl": "target", "msg": "<6>[    0.527640] iommu: Default domain type: Translated"}
- {"dt": "2025-10-15T07:00:43.775369", "lvl": "target", "msg": "<6>[    0.527812] iommu: DMA domain TLB invalidation policy: lazy mode"}
- {"dt": "2025-10-15T07:00:43.778201", "lvl": "target", "msg": "<5>[    0.530573] SCSI subsystem initialized"}
- {"dt": "2025-10-15T07:00:43.779118", "lvl": "target", "msg": "<7>[    0.531521] libata version 3.00 loaded."}
- {"dt": "2025-10-15T07:00:43.780014", "lvl": "target", "msg": "<6>[    0.532418] ACPI: bus type USB registered"}
- {"dt": "2025-10-15T07:00:43.780567", "lvl": "target", "msg": "<6>[    0.532973] usbcore: registered new interface driver usbfs"}
- {"dt": "2025-10-15T07:00:43.780963", "lvl": "target", "msg": "<6>[    0.533483] usbcore: registered new interface driver hub"}
- {"dt": "2025-10-15T07:00:43.781275", "lvl": "target", "msg": "<6>[    0.533737] usbcore: registered new device driver usb"}
- {"dt": "2025-10-15T07:00:43.781661", "lvl": "target", "msg": "<6>[    0.534187] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-10-15T07:00:43.781905", "lvl": "target", "msg": "<6>[    0.534323] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-10-15T07:00:43.782218", "lvl": "target", "msg": "<6>[    0.534454] PTP clock support registered"}
- {"dt": "2025-10-15T07:00:43.784177", "lvl": "target", "msg": "<6>[    0.536572] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2025-10-15T07:00:43.792125", "lvl": "target", "msg": "<6>[    0.544479] NetLabel: Initializing"}
- {"dt": "2025-10-15T07:00:43.792242", "lvl": "target", "msg": "<6>[    0.544586] NetLabel:  domain hash size = 128"}
- {"dt": "2025-10-15T07:00:43.792364", "lvl": "target", "msg": "<6>[    0.544698] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO"}
- {"dt": "2025-10-15T07:00:43.793149", "lvl": "target", "msg": "<6>[    0.545507] NetLabel:  unlabeled traffic allowed by default"}
- {"dt": "2025-10-15T07:00:43.797913", "lvl": "target", "msg": "<6>[    0.550268] PCI: Using ACPI for IRQ routing"}
- {"dt": "2025-10-15T07:00:43.801097", "lvl": "target", "msg": "<7>[    0.553464] PCI: pci_cache_line_size set to 64 bytes"}
- {"dt": "2025-10-15T07:00:43.801485", "lvl": "target", "msg": "<7>[    0.553964] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]"}
- {"dt": "2025-10-15T07:00:43.801845", "lvl": "target", "msg": "<7>[    0.554266] e820: reserve RAM buffer [mem 0x7ffdf000-0x7fffffff]"}
- {"dt": "2025-10-15T07:00:43.803534", "lvl": "target", "msg": "<6>[    0.555879] pci 0000:00:01.0: vgaarb: setting as boot VGA device"}
- {"dt": "2025-10-15T07:00:43.803654", "lvl": "target", "msg": "<6>[    0.556094] pci 0000:00:01.0: vgaarb: bridge control possible"}
- {"dt": "2025-10-15T07:00:43.804046", "lvl": "target", "msg": "<6>[    0.556276] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none"}
- {"dt": "2025-10-15T07:00:43.804142", "lvl": "target", "msg": "<6>[    0.556420] vgaarb: loaded"}
- {"dt": "2025-10-15T07:00:43.811276", "lvl": "target", "msg": "<6>[    0.562797] clocksource: Switched to clocksource tsc-early"}
- {"dt": "2025-10-15T07:00:43.813514", "lvl": "target", "msg": "<5>[    0.565038] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2025-10-15T07:00:43.813651", "lvl": "target", "msg": "<6>[    0.565287] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)"}
- {"dt": "2025-10-15T07:00:43.818576", "lvl": "target", "msg": "<6>[    0.570146] pnp: PnP ACPI init"}
- {"dt": "2025-10-15T07:00:43.822211", "lvl": "target", "msg": "<6>[    0.573768] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved"}
- {"dt": "2025-10-15T07:00:43.825053", "lvl": "target", "msg": "<6>[    0.576619] pnp: PnP ACPI: found 6 devices"}
- {"dt": "2025-10-15T07:00:43.877304", "lvl": "target", "msg": "<6>[    0.628817] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns"}
- {"dt": "2025-10-15T07:00:43.878121", "lvl": "target", "msg": "<6>[    0.629687] NET: Registered PF_INET protocol family"}
- {"dt": "2025-10-15T07:00:43.879609", "lvl": "target", "msg": "<6>[    0.631169] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.886890", "lvl": "target", "msg": "<6>[    0.638418] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.887166", "lvl": "target", "msg": "<6>[    0.638767] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.887534", "lvl": "target", "msg": "<6>[    0.639062] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.887877", "lvl": "target", "msg": "<6>[    0.639456] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.888212", "lvl": "target", "msg": "<6>[    0.639886] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2025-10-15T07:00:43.889464", "lvl": "target", "msg": "<6>[    0.641022] UDP hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.889792", "lvl": "target", "msg": "<6>[    0.641429] UDP-Lite hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-10-15T07:00:43.891382", "lvl": "target", "msg": "<6>[    0.642956] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-10-15T07:00:43.893740", "lvl": "target", "msg": "<6>[    0.645276] RPC: Registered named UNIX socket transport module."}
- {"dt": "2025-10-15T07:00:43.893870", "lvl": "target", "msg": "<6>[    0.645468] RPC: Registered udp transport module."}
- {"dt": "2025-10-15T07:00:43.893957", "lvl": "target", "msg": "<6>[    0.645640] RPC: Registered tcp transport module."}
- {"dt": "2025-10-15T07:00:43.894092", "lvl": "target", "msg": "<6>[    0.645767] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2025-10-15T07:00:43.894253", "lvl": "target", "msg": "<6>[    0.645914] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2025-10-15T07:00:43.897206", "lvl": "target", "msg": "<6>[    0.648768] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]"}
- {"dt": "2025-10-15T07:00:43.897333", "lvl": "target", "msg": "<6>[    0.648959] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]"}
- {"dt": "2025-10-15T07:00:43.897529", "lvl": "target", "msg": "<6>[    0.649150] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]"}
- {"dt": "2025-10-15T07:00:43.897755", "lvl": "target", "msg": "<6>[    0.649349] pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window]"}
- {"dt": "2025-10-15T07:00:43.897954", "lvl": "target", "msg": "<6>[    0.649588] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]"}
- {"dt": "2025-10-15T07:00:43.898584", "lvl": "target", "msg": "<6>[    0.650161] PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-10-15T07:00:44.451324", "lvl": "target", "msg": "<5>[    1.202762] Initialise system trusted keyrings"}
- {"dt": "2025-10-15T07:00:44.452879", "lvl": "target", "msg": "<6>[    1.204413] workingset: timestamp_bits=30 max_order=18 bucket_order=0"}
- {"dt": "2025-10-15T07:00:44.457227", "lvl": "target", "msg": "<5>[    1.208764] NFS: Registering the id_resolver key type"}
- {"dt": "2025-10-15T07:00:44.457771", "lvl": "target", "msg": "<5>[    1.209342] Key type id_resolver registered"}
- {"dt": "2025-10-15T07:00:44.457843", "lvl": "target", "msg": "<5>[    1.209467] Key type id_legacy registered"}
- {"dt": "2025-10-15T07:00:44.458816", "lvl": "target", "msg": "<6>[    1.210397] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2025-10-15T07:00:44.582668", "lvl": "target", "msg": "<5>[    1.334094] Key type asymmetric registered"}
- {"dt": "2025-10-15T07:00:44.582837", "lvl": "target", "msg": "<5>[    1.334336] Asymmetric key parser 'x509' registered"}
- {"dt": "2025-10-15T07:00:44.583086", "lvl": "target", "msg": "<6>[    1.334714] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)"}
- {"dt": "2025-10-15T07:00:44.583698", "lvl": "target", "msg": "<6>[    1.335278] io scheduler mq-deadline registered"}
- {"dt": "2025-10-15T07:00:44.583780", "lvl": "target", "msg": "<6>[    1.335434] io scheduler kyber registered"}
- {"dt": "2025-10-15T07:00:44.586440", "lvl": "target", "msg": "<6>[    1.338001] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0"}
- {"dt": "2025-10-15T07:00:44.589761", "lvl": "target", "msg": "<6>[    1.341322] ACPI: button: Power Button [PWRF]"}
- {"dt": "2025-10-15T07:00:44.593576", "lvl": "target", "msg": "<6>[    1.345049] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2025-10-15T07:00:44.597850", "lvl": "target", "msg": "<6>[    1.349388] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A"}
- {"dt": "2025-10-15T07:00:44.603026", "lvl": "target", "msg": "<4>[    1.354577] hpet_acpi_add: no address or irqs in _CRS"}
- {"dt": "2025-10-15T07:00:44.604320", "lvl": "target", "msg": "<6>[    1.355889] Non-volatile memory driver v1.3"}
- {"dt": "2025-10-15T07:00:44.604673", "lvl": "target", "msg": "<6>[    1.356334] Linux agpgart interface v0.103"}
- {"dt": "2025-10-15T07:00:44.606536", "lvl": "target", "msg": "<6>[    1.358100] ACPI: bus type drm_connector registered"}
- {"dt": "2025-10-15T07:00:44.623599", "lvl": "target", "msg": "<6>[    1.375146] loop: module loaded"}
- {"dt": "2025-10-15T07:00:44.629245", "lvl": "target", "msg": "<6>[    1.380796] ACPI: \\_SB_.GSIA: Enabled at IRQ 16"}
- {"dt": "2025-10-15T07:00:44.637501", "lvl": "target", "msg": "<6>[    1.389057] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode"}
- {"dt": "2025-10-15T07:00:44.637716", "lvl": "target", "msg": "<6>[    1.389349] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)"}
- {"dt": "2025-10-15T07:00:44.638012", "lvl": "target", "msg": "<6>[    1.389606] ahci 0000:00:1f.2: flags: 64bit ncq only "}
- {"dt": "2025-10-15T07:00:44.644786", "lvl": "target", "msg": "<6>[    1.396341] scsi host0: ahci"}
- {"dt": "2025-10-15T07:00:44.646407", "lvl": "target", "msg": "<6>[    1.397972] scsi host1: ahci"}
- {"dt": "2025-10-15T07:00:44.647292", "lvl": "target", "msg": "<6>[    1.398839] scsi host2: ahci"}
- {"dt": "2025-10-15T07:00:44.648154", "lvl": "target", "msg": "<6>[    1.399740] scsi host3: ahci"}
- {"dt": "2025-10-15T07:00:44.648972", "lvl": "target", "msg": "<6>[    1.400547] scsi host4: ahci"}
- {"dt": "2025-10-15T07:00:44.649806", "lvl": "target", "msg": "<6>[    1.401385] scsi host5: ahci"}
- {"dt": "2025-10-15T07:00:44.650730", "lvl": "target", "msg": "<6>[    1.402306] ata1: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1100 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.650979", "lvl": "target", "msg": "<6>[    1.402541] ata2: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1180 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.651223", "lvl": "target", "msg": "<6>[    1.402800] ata3: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1200 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.651583", "lvl": "target", "msg": "<6>[    1.403042] ata4: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1280 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.651750", "lvl": "target", "msg": "<6>[    1.403326] ata5: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1300 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.651997", "lvl": "target", "msg": "<6>[    1.403578] ata6: SATA max UDMA/133 abar m4096@0xfebf1000 port 0xfebf1380 irq 24 lpm-pol 1"}
- {"dt": "2025-10-15T07:00:44.658356", "lvl": "target", "msg": "<6>[    1.409900] e100: Intel(R) PRO/100 Network Driver"}
- {"dt": "2025-10-15T07:00:44.658455", "lvl": "target", "msg": "<6>[    1.410056] e100: Copyright(c) 1999-2006 Intel Corporation"}
- {"dt": "2025-10-15T07:00:44.658785", "lvl": "target", "msg": "<6>[    1.410358] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-10-15T07:00:44.658889", "lvl": "target", "msg": "<6>[    1.410504] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2025-10-15T07:00:44.659221", "lvl": "target", "msg": "<6>[    1.410788] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-10-15T07:00:44.659315", "lvl": "target", "msg": "<6>[    1.410927] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2025-10-15T07:00:44.659590", "lvl": "target", "msg": "<6>[    1.411231] sky2: driver version 1.30"}
- {"dt": "2025-10-15T07:00:44.662001", "lvl": "target", "msg": "<6>[    1.413552] usbcore: registered new interface driver usblp"}
- {"dt": "2025-10-15T07:00:44.662234", "lvl": "target", "msg": "<6>[    1.413849] usbcore: registered new interface driver usb-storage"}
- {"dt": "2025-10-15T07:00:44.663248", "lvl": "target", "msg": "<6>[    1.414811] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12"}
- {"dt": "2025-10-15T07:00:44.666474", "lvl": "target", "msg": "<6>[    1.418032] serio: i8042 KBD port at 0x60,0x64 irq 1"}
- {"dt": "2025-10-15T07:00:44.666773", "lvl": "target", "msg": "<6>[    1.418382] serio: i8042 AUX port at 0x60,0x64 irq 12"}
- {"dt": "2025-10-15T07:00:44.669004", "lvl": "target", "msg": "<6>[    1.420565] rtc_cmos 00:04: RTC can wake from S4"}
- {"dt": "2025-10-15T07:00:44.673128", "lvl": "target", "msg": "<6>[    1.424660] rtc_cmos 00:04: registered as rtc0"}
- {"dt": "2025-10-15T07:00:44.673596", "lvl": "target", "msg": "<6>[    1.424916] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1"}
- {"dt": "2025-10-15T07:00:44.674025", "lvl": "target", "msg": "<6>[    1.425618] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram"}
- {"dt": "2025-10-15T07:00:44.676507", "lvl": "target", "msg": "<6>[    1.428056] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev"}
- {"dt": "2025-10-15T07:00:44.677229", "lvl": "target", "msg": "<6>[    1.428741] intel_pstate: CPU model not supported"}
- {"dt": "2025-10-15T07:00:44.677986", "lvl": "target", "msg": "<6>[    1.429486] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2025-10-15T07:00:44.678900", "lvl": "target", "msg": "<6>[    1.429797] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt"}
- {"dt": "2025-10-15T07:00:44.681275", "lvl": "target", "msg": "<6>[    1.432806] usbcore: registered new interface driver usbhid"}
- {"dt": "2025-10-15T07:00:44.681372", "lvl": "target", "msg": "<6>[    1.432982] usbhid: USB HID core driver"}
- {"dt": "2025-10-15T07:00:44.682366", "lvl": "target", "msg": "<6>[    1.433922] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD"}
- {"dt": "2025-10-15T07:00:44.688313", "lvl": "target", "msg": "<6>[    1.439882] Initializing XFRM netlink socket"}
- {"dt": "2025-10-15T07:00:44.688617", "lvl": "target", "msg": "<6>[    1.440287] NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-10-15T07:00:44.694305", "lvl": "target", "msg": "<6>[    1.445848] Segment Routing with IPv6"}
- {"dt": "2025-10-15T07:00:44.694417", "lvl": "target", "msg": "<6>[    1.446109] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-10-15T07:00:44.695347", "lvl": "target", "msg": "<6>[    1.446913] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-10-15T07:00:44.698004", "lvl": "target", "msg": "<6>[    1.449572] NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-10-15T07:00:44.699158", "lvl": "target", "msg": "<6>[    1.450747] 9pnet: Installing 9P2000 support"}
- {"dt": "2025-10-15T07:00:44.699425", "lvl": "target", "msg": "<5>[    1.451041] Key type dns_resolver registered"}
- {"dt": "2025-10-15T07:00:44.701560", "lvl": "target", "msg": "<6>[    1.453144] IPI shorthand broadcast: enabled"}
- {"dt": "2025-10-15T07:00:44.723603", "lvl": "target", "msg": "<6>[    1.475117] sched_clock: Marking stable (1436030846, 38164149)->(1482984182, -8789187)"}
- {"dt": "2025-10-15T07:00:44.725678", "lvl": "target", "msg": "<6>[    1.477227] registered taskstats version 1"}
- {"dt": "2025-10-15T07:00:44.725756", "lvl": "target", "msg": "<5>[    1.477367] Loading compiled-in X.509 certificates"}
- {"dt": "2025-10-15T07:00:44.738470", "lvl": "target", "msg": "<6>[    1.490006] PM:   Magic number: 13:852:15"}
- {"dt": "2025-10-15T07:00:44.738793", "lvl": "target", "msg": "<6>[    1.490376] netconsole: network logging started"}
- {"dt": "2025-10-15T07:00:44.739928", "lvl": "target", "msg": "<5>[    1.491513] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2025-10-15T07:00:44.747503", "lvl": "target", "msg": "<6>[    1.499061] kworker/u8:6 (69) used greatest stack depth: 6948 bytes left"}
- {"dt": "2025-10-15T07:00:44.757256", "lvl": "target", "msg": "<5>[    1.508818] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2025-10-15T07:00:44.759456", "lvl": "target", "msg": "<5>[    1.511011] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2025-10-15T07:00:44.760994", "lvl": "target", "msg": "<4>[    1.512575] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2025-10-15T07:00:44.761309", "lvl": "target", "msg": "<6>[    1.512990] cfg80211: failed to load regulatory.db"}
- {"dt": "2025-10-15T07:00:44.761603", "lvl": "target", "msg": "<6>[    1.513131] ALSA device list:"}
- {"dt": "2025-10-15T07:00:44.761801", "lvl": "target", "msg": "<6>[    1.513468]   No soundcards found."}
- {"dt": "2025-10-15T07:00:44.768232", "lvl": "target", "msg": "<4>[    1.519724] check access for rdinit=/init failed: -2, ignoring"}
- {"dt": "2025-10-15T07:00:44.951946", "lvl": "target", "msg": "<6>[    1.703459] tsc: Refined TSC clocksource calibration: 3393.571 MHz"}
- {"dt": "2025-10-15T07:00:44.952618", "lvl": "target", "msg": "<6>[    1.704036] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x30ea95cc56c, max_idle_ns: 440795297794 ns"}
- {"dt": "2025-10-15T07:00:44.953155", "lvl": "target", "msg": "<6>[    1.704722] clocksource: Switched to clocksource tsc"}
- {"dt": "2025-10-15T07:00:44.966929", "lvl": "target", "msg": "<6>[    1.718478] ata4: SATA link down (SStatus 0 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.968802", "lvl": "target", "msg": "<6>[    1.720380] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.970539", "lvl": "target", "msg": "<6>[    1.722120] ata6: SATA link down (SStatus 0 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.971876", "lvl": "target", "msg": "<6>[    1.723434] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100"}
- {"dt": "2025-10-15T07:00:44.972228", "lvl": "target", "msg": "<6>[    1.723840] ata1.00: 5310208 sectors, multi 16: LBA48 NCQ (depth 32)"}
- {"dt": "2025-10-15T07:00:44.972799", "lvl": "target", "msg": "<6>[    1.724275] ata1.00: applying bridge limits"}
- {"dt": "2025-10-15T07:00:44.973644", "lvl": "target", "msg": "<6>[    1.725220] ata2: SATA link down (SStatus 0 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.973980", "lvl": "target", "msg": "<6>[    1.725612] ata5: SATA link down (SStatus 0 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.974625", "lvl": "target", "msg": "<6>[    1.726205] ata1.00: configured for UDMA/100"}
- {"dt": "2025-10-15T07:00:44.975002", "lvl": "target", "msg": "<6>[    1.726648] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)"}
- {"dt": "2025-10-15T07:00:44.975511", "lvl": "target", "msg": "<6>[    1.727091] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100"}
- {"dt": "2025-10-15T07:00:44.975579", "lvl": "target", "msg": "<6>[    1.727266] ata3.00: applying bridge limits"}
- {"dt": "2025-10-15T07:00:44.975899", "lvl": "target", "msg": "<6>[    1.727596] ata3.00: configured for UDMA/100"}
- {"dt": "2025-10-15T07:00:44.983115", "lvl": "target", "msg": "<5>[    1.734667] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5"}
- {"dt": "2025-10-15T07:00:44.989117", "lvl": "target", "msg": "<5>[    1.740666] sd 0:0:0:0: Attached scsi generic sg0 type 0"}
- {"dt": "2025-10-15T07:00:44.990555", "lvl": "target", "msg": "<5>[    1.742134] sd 0:0:0:0: [sda] 5310208 512-byte logical blocks: (2.72 GB/2.53 GiB)"}
- {"dt": "2025-10-15T07:00:44.991680", "lvl": "target", "msg": "<5>[    1.743264] sd 0:0:0:0: [sda] Write Protect is off"}
- {"dt": "2025-10-15T07:00:44.991855", "lvl": "target", "msg": "<7>[    1.743534] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00"}
- {"dt": "2025-10-15T07:00:44.992089", "lvl": "target", "msg": "<5>[    1.743592] scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5"}
- {"dt": "2025-10-15T07:00:44.992620", "lvl": "target", "msg": "<5>[    1.744104] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA"}
- {"dt": "2025-10-15T07:00:44.994337", "lvl": "target", "msg": "<6>[    1.745901] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes"}
- {"dt": "2025-10-15T07:00:45.013673", "lvl": "target", "msg": "<5>[    1.765205] sd 0:0:0:0: [sda] Attached SCSI disk"}
- {"dt": "2025-10-15T07:00:45.016682", "lvl": "target", "msg": "<6>[    1.768241] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray"}
- {"dt": "2025-10-15T07:00:45.017033", "lvl": "target", "msg": "<6>[    1.768707] cdrom: Uniform CD-ROM driver Revision: 3.20"}
- {"dt": "2025-10-15T07:00:45.029443", "lvl": "target", "msg": "<7>[    1.781008] sr 2:0:0:0: Attached scsi CD-ROM sr0"}
- {"dt": "2025-10-15T07:00:45.030292", "lvl": "target", "msg": "<5>[    1.781872] sr 2:0:0:0: Attached scsi generic sg1 type 5"}
- {"dt": "2025-10-15T07:00:45.096149", "lvl": "target", "msg": "<6>[    1.847679] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3"}
- {"dt": "2025-10-15T07:00:45.097046", "lvl": "target", "msg": "<6>[    1.848583] md: Waiting for all devices to be available before autodetect"}
- {"dt": "2025-10-15T07:00:45.097163", "lvl": "target", "msg": "<6>[    1.848795] md: If you don't use raid, use raid=noautodetect"}
- {"dt": "2025-10-15T07:00:45.097314", "lvl": "target", "msg": "<6>[    1.849023] md: Autodetecting RAID arrays."}
- {"dt": "2025-10-15T07:00:45.097405", "lvl": "target", "msg": "<6>[    1.849156] md: autorun ..."}
- {"dt": "2025-10-15T07:00:45.097495", "lvl": "target", "msg": "<6>[    1.849236] md: ... autorun DONE."}
- {"dt": "2025-10-15T07:00:45.121018", "lvl": "target", "msg": "<6>[    1.872288] EXT4-fs (sda): mounted filesystem a393eee5-6c88-4987-891b-ee01fb115e8c r/w with ordered data mode. Quota mode: none."}
- {"dt": "2025-10-15T07:00:45.121410", "lvl": "target", "msg": "<6>[    1.873065] VFS: Mounted root (ext4 filesystem) on device 8:0."}
- {"dt": "2025-10-15T07:00:45.123261", "lvl": "target", "msg": "<6>[    1.874818] devtmpfs: mounted"}
- {"dt": "2025-10-15T07:00:45.229854", "lvl": "target", "msg": "<6>[    1.981372] Freeing unused kernel image (initmem) memory: 876K"}
- {"dt": "2025-10-15T07:00:45.230688", "lvl": "target", "msg": "<6>[    1.982242] Write protecting kernel text and read-only data: 20572k"}
- {"dt": "2025-10-15T07:00:45.230976", "lvl": "target", "msg": "<6>[    1.982661] Run /sbin/init as init process"}
- {"dt": "2025-10-15T07:00:45.231051", "lvl": "target", "msg": "<7>[    1.982778]   with arguments:"}
- {"dt": "2025-10-15T07:00:45.231121", "lvl": "target", "msg": "<7>[    1.982871]     /sbin/init"}
- {"dt": "2025-10-15T07:00:45.231213", "lvl": "target", "msg": "<7>[    1.982957]     verbose"}
- {"dt": "2025-10-15T07:00:45.231296", "lvl": "target", "msg": "<7>[    1.983035]   with environment:"}
- {"dt": "2025-10-15T07:00:45.231371", "lvl": "target", "msg": "<7>[    1.983131]     HOME=/"}
- {"dt": "2025-10-15T07:00:45.231457", "lvl": "target", "msg": "<7>[    1.983208]     TERM=linux"}
- {"dt": "2025-10-15T07:00:45.390549", "lvl": "target", "msg": "SELinux:  Could not open policy file <= /etc/selinux/default/policy/policy.35:  No such file or directory"}
- {"dt": "2025-10-15T07:00:45.489497", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2025-10-15T07:00:45.489817", "lvl": "target", "msg": "[0;1;39mWelcome to [0m[1mDebian GNU/Linux 13 (trixie)[0m[0;1;39m![0m"}
- {"dt": "2025-10-15T07:00:45.489890", "lvl": "target", "msg": "\r"}
- {"dt": "2025-10-15T07:00:45.685425", "lvl": "target", "msg": "<6>[    2.436926] nfs-server-gene (78) used greatest stack depth: 6112 bytes left"}
- {"dt": "2025-10-15T07:00:45.778101", "lvl": "target", "msg": "<6>[    2.529617] systemd-run-gen (86) used greatest stack depth: 5748 bytes left"}
- {"dt": "2025-10-15T07:00:45.783252", "lvl": "target", "msg": "<6>[    2.534781] systemd-rc-loca (85) used greatest stack depth: 5596 bytes left"}
- {"dt": "2025-10-15T07:00:46.626791", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2025-10-15T07:00:46.631332", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe.\r"}
- {"dt": "2025-10-15T07:00:46.635108", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty.\r"}
- {"dt": "2025-10-15T07:00:46.638658", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-xfs_scrub.sli\u2026[0mxfs_scrub background service slice.\r"}
- {"dt": "2025-10-15T07:00:46.641010", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2025-10-15T07:00:46.643762", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch.\r"}
- {"dt": "2025-10-15T07:00:46.644841", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch.\r"}
- {"dt": "2025-10-15T07:00:46.650121", "lvl": "target", "msg": "[[0;32m  OK  [0m] Set up automount [0;1;39mproc-sys-fs-binfm\u2026[0mormats File System Automount Point.\r"}
- {"dt": "2025-10-15T07:00:46.651818", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0...\r"}
- {"dt": "2025-10-15T07:00:46.652789", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork.target[0m - Network.\r"}
- {"dt": "2025-10-15T07:00:46.653545", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online.\r"}
- {"dt": "2025-10-15T07:00:46.654424", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups.\r"}
- {"dt": "2025-10-15T07:00:46.655176", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2025-10-15T07:00:46.656065", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2025-10-15T07:00:46.656943", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2025-10-15T07:00:46.665737", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mrpcbind.socket[0m - RPCbind Server Activation Socket.\r"}
- {"dt": "2025-10-15T07:00:46.674956", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption.\r"}
- {"dt": "2025-10-15T07:00:46.677170", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe.\r"}
- {"dt": "2025-10-15T07:00:46.679069", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2025-10-15T07:00:46.680473", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets.\r"}
- {"dt": "2025-10-15T07:00:46.683589", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket.\r"}
- {"dt": "2025-10-15T07:00:46.684739", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2025-10-15T07:00:46.707657", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System...\r"}
- {"dt": "2025-10-15T07:00:46.716795", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System...\r"}
- {"dt": "2025-10-15T07:00:46.730587", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock...\r"}
- {"dt": "2025-10-15T07:00:46.744599", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System...\r"}
- {"dt": "2025-10-15T07:00:46.765027", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System...\r"}
- {"dt": "2025-10-15T07:00:46.785675", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp...\r"}
- {"dt": "2025-10-15T07:00:46.813218", "lvl": "target", "msg": "<5>[    3.564652] random: crng init done"}
- {"dt": "2025-10-15T07:00:46.823026", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs...\r"}
- {"dt": "2025-10-15T07:00:46.870786", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2025-10-15T07:00:46.880115", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore...\r"}
- {"dt": "2025-10-15T07:00:46.897470", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse...\r"}
- {"dt": "2025-10-15T07:00:46.901895", "lvl": "target", "msg": "<28>[    3.653152] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling."}
- {"dt": "2025-10-15T07:00:46.902435", "lvl": "target", "msg": "<28>[    3.653713] systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.)"}
- {"dt": "2025-10-15T07:00:46.914345", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2025-10-15T07:00:46.928025", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules...\r"}
- {"dt": "2025-10-15T07:00:46.938389", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems...\r"}
- {"dt": "2025-10-15T07:00:46.949322", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully...\r"}
- {"dt": "2025-10-15T07:00:46.964432", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials...\r"}
- {"dt": "2025-10-15T07:00:46.972269", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices...\r"}
- {"dt": "2025-10-15T07:00:47.004114", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System.\r"}
- {"dt": "2025-10-15T07:00:47.005448", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System.\r"}
- {"dt": "2025-10-15T07:00:47.007439", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock.\r"}
- {"dt": "2025-10-15T07:00:47.008339", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System.\r"}
- {"dt": "2025-10-15T07:00:47.010389", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System.\r"}
- {"dt": "2025-10-15T07:00:47.011336", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp.\r"}
- {"dt": "2025-10-15T07:00:47.094075", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules.\r"}
- {"dt": "2025-10-15T07:00:47.115039", "lvl": "target", "msg": "         Mounting [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem...\r"}
- {"dt": "2025-10-15T07:00:47.130299", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables...\r"}
- {"dt": "2025-10-15T07:00:47.191684", "lvl": "target", "msg": "<6>[    3.943152] mount (110) used greatest stack depth: 5548 bytes left"}
- {"dt": "2025-10-15T07:00:47.194521", "lvl": "target", "msg": "<28>[    3.946068] systemd[1]: proc-fs-nfsd.mount: Failed with result 'exit-code'."}
- {"dt": "2025-10-15T07:00:47.200050", "lvl": "target", "msg": "<27>[    3.951603] systemd[1]: Failed to mount proc-fs-nfsd.mount - NFSD configuration filesystem."}
- {"dt": "2025-10-15T07:00:47.200718", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem.\r"}
- {"dt": "2025-10-15T07:00:47.202220", "lvl": "target", "msg": "See 'systemctl status proc-fs-nfsd.mount' for details.\r"}
- {"dt": "2025-10-15T07:00:47.203266", "lvl": "target", "msg": "<28>[    3.954851] systemd[1]: Dependency failed for nfs-server.service - NFS server and services."}
- {"dt": "2025-10-15T07:00:47.203972", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-server.service[0m - NFS server and services.\r"}
- {"dt": "2025-10-15T07:00:47.204937", "lvl": "target", "msg": "<28>[    3.956527] systemd[1]: Dependency failed for nfs-idmapd.service - NFSv4 ID-name mapping service."}
- {"dt": "2025-10-15T07:00:47.205585", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-idmapd.s\u2026ce[0m - NFSv4 ID-name mapping service.\r"}
- {"dt": "2025-10-15T07:00:47.208326", "lvl": "target", "msg": "<28>[    3.959896] systemd[1]: Dependency failed for nfs-mountd.service - NFS Mount Daemon."}
- {"dt": "2025-10-15T07:00:47.208982", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-mountd.service[0m - NFS Mount Daemon.\r"}
- {"dt": "2025-10-15T07:00:47.209335", "lvl": "target", "msg": "<28>[    3.960938] systemd[1]: Dependency failed for nfsdcld.service - NFSv4 Client Tracking Daemon."}
- {"dt": "2025-10-15T07:00:47.211665", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfsdcld.service[0m - NFSv4 Client Tracking Daemon.\r"}
- {"dt": "2025-10-15T07:00:47.228032", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs.\r"}
- {"dt": "2025-10-15T07:00:47.234326", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems.\r"}
- {"dt": "2025-10-15T07:00:47.258426", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed...\r"}
- {"dt": "2025-10-15T07:00:47.264738", "lvl": "target", "msg": "<6>[    4.016276] udevadm (105) used greatest stack depth: 5492 bytes left"}
- {"dt": "2025-10-15T07:00:47.269172", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials.\r"}
- {"dt": "2025-10-15T07:00:47.284360", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2025-10-15T07:00:47.291245", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2025-10-15T07:00:47.299383", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2025-10-15T07:00:47.409084", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully.\r"}
- {"dt": "2025-10-15T07:00:47.425503", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users...\r"}
- {"dt": "2025-10-15T07:00:47.433025", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2025-10-15T07:00:47.454522", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed.\r"}
- {"dt": "2025-10-15T07:00:47.576893", "lvl": "target", "msg": "<6>[    4.328284] systemd-sysuser (116) used greatest stack depth: 5448 bytes left"}
- {"dt": "2025-10-15T07:00:47.580659", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2025-10-15T07:00:47.596565", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev...\r"}
- {"dt": "2025-10-15T07:00:47.769778", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev.\r"}
- {"dt": "2025-10-15T07:00:47.771234", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems.\r"}
- {"dt": "2025-10-15T07:00:47.772402", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2025-10-15T07:00:47.787281", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management.\r"}
- {"dt": "2025-10-15T07:00:47.795580", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart...\r"}
- {"dt": "2025-10-15T07:00:47.807078", "lvl": "target", "msg": "         Starting [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats...\r"}
- {"dt": "2025-10-15T07:00:47.818334", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files...\r"}
- {"dt": "2025-10-15T07:00:47.826048", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2025-10-15T07:00:47.911136", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage...\r"}
- {"dt": "2025-10-15T07:00:47.980645", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart.\r"}
- {"dt": "2025-10-15T07:00:48.005686", "lvl": "target", "msg": "         Mounting [0;1;39mproc-sys-fs-binfmt_misc.m\u2026[0mcutable File Formats File System...\r"}
- {"dt": "2025-10-15T07:00:48.061538", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mproc-sys-fs-binfmt_misc.mo\u2026[0mxecutable File Formats File System.\r"}
- {"dt": "2025-10-15T07:00:48.078452", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats.\r"}
- {"dt": "2025-10-15T07:00:48.232146", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices.\r"}
- {"dt": "2025-10-15T07:00:48.246901", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage.\r"}
- {"dt": "2025-10-15T07:00:48.260073", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories...\r"}
- {"dt": "2025-10-15T07:00:48.296468", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files.\r"}
- {"dt": "2025-10-15T07:00:48.437970", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories.\r"}
- {"dt": "2025-10-15T07:00:48.454864", "lvl": "target", "msg": "         Mounting [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System...\r"}
- {"dt": "2025-10-15T07:00:48.465973", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache...\r"}
- {"dt": "2025-10-15T07:00:48.478632", "lvl": "target", "msg": "         Starting [0;1;39mrpcbind.service[0m - RPC bind portmap service...\r"}
- {"dt": "2025-10-15T07:00:48.490415", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog...\r"}
- {"dt": "2025-10-15T07:00:48.566720", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System.\r"}
- {"dt": "2025-10-15T07:00:48.568066", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpc_pipefs.target[0m.\r"}
- {"dt": "2025-10-15T07:00:48.579229", "lvl": "target", "msg": "         Starting [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon...\r"}
- {"dt": "2025-10-15T07:00:48.580382", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnfs-client.target[0m - NFS client services.\r"}
- {"dt": "2025-10-15T07:00:48.642030", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpcbind.service[0m - RPC bind portmap service.\r"}
- {"dt": "2025-10-15T07:00:48.643622", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs-pre.targe\u2026[0mreparation for Remote File Systems.\r"}
- {"dt": "2025-10-15T07:00:48.645311", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2025-10-15T07:00:48.647129", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpcbind.target[0m - RPC Port Mapper.\r"}
- {"dt": "2025-10-15T07:00:48.658636", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd.service[0m - NFS s\u2026tus monitor for NFSv2/3 locking....\r"}
- {"dt": "2025-10-15T07:00:48.697009", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon.\r"}
- {"dt": "2025-10-15T07:00:48.931382", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd.service[0m - NFS status monitor for NFSv2/3 locking..\r"}
- {"dt": "2025-10-15T07:00:48.968638", "lvl": "target", "msg": "<6>[    5.720112] journalctl (168) used greatest stack depth: 5332 bytes left"}
- {"dt": "2025-10-15T07:00:48.972969", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog.\r"}
- {"dt": "2025-10-15T07:00:49.016819", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache.\r"}
- {"dt": "2025-10-15T07:00:49.033208", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed...\r"}
- {"dt": "2025-10-15T07:00:49.101997", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed.\r"}
- {"dt": "2025-10-15T07:00:49.106677", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2025-10-15T07:00:49.113511", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2025-10-15T07:00:49.115290", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities.\r"}
- {"dt": "2025-10-15T07:00:49.116549", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer.\r"}
- {"dt": "2025-10-15T07:00:49.119798", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-10-15T07:00:49.121654", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week.\r"}
- {"dt": "2025-10-15T07:00:49.124747", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files.\r"}
- {"dt": "2025-10-15T07:00:49.126130", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories.\r"}
- {"dt": "2025-10-15T07:00:49.129508", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mxfs_scrub_all.timer[0m - Peri\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-10-15T07:00:49.130128", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2025-10-15T07:00:49.132886", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket.\r"}
- {"dt": "2025-10-15T07:00:49.133993", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket.\r"}
- {"dt": "2025-10-15T07:00:49.136091", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket.\r"}
- {"dt": "2025-10-15T07:00:49.137539", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2025-10-15T07:00:49.139610", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2025-10-15T07:00:49.152334", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon.\r"}
- {"dt": "2025-10-15T07:00:49.164058", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2025-10-15T07:00:49.183183", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots...\r"}
- {"dt": "2025-10-15T07:00:49.193274", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfsidd.service[0m - NFS FSID Daemon.\r"}
- {"dt": "2025-10-15T07:00:49.211460", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management...\r"}
- {"dt": "2025-10-15T07:00:49.227188", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions...\r"}
- {"dt": "2025-10-15T07:00:49.256633", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0.\r"}
- {"dt": "2025-10-15T07:00:49.359830", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions.\r"}
- {"dt": "2025-10-15T07:00:49.371949", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2025-10-15T07:00:49.383622", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyS0.service[0m - Serial Getty on ttyS0.\r"}
- {"dt": "2025-10-15T07:00:49.384908", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2025-10-15T07:00:49.530586", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2025-10-15T07:00:49.674316", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots.\r"}
- {"dt": "2025-10-15T07:00:49.895587", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2025-10-15T07:00:49.908190", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2025-10-15T07:00:49.909915", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2025-10-15T07:00:51.632901", "lvl": "target", "msg": "[!p]104\a[?7h[6n[32766;32766H[6n[!p]104\a[?7h[6n[32766;32766H[6n\r"}
- {"dt": "2025-10-15T07:00:51.633088", "lvl": "target", "msg": "Debian GNU/Linux 13 runner-rgetuxoh-project-40964107-concurrent-0 ttyS0"}
- {"dt": "2025-10-15T07:00:51.633227", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:51.633640", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2025-10-15T07:00:51.633716", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "8.29", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-10-15T07:00:51.633814", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-10-15T07:00:51.633875", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-10-15T07:00:51.734110", "lvl": "input", "msg": "root"}
- {"dt": "2025-10-15T07:00:51.734255", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-10-15T07:00:51.734349", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:19:51)"}
- {"dt": "2025-10-15T07:00:51.734432", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:09:55)"}
- {"dt": "2025-10-15T07:00:51.735153", "lvl": "target", "msg": "runner-rgetuxoh-project-40964107-concurrent-0 login: root\r"}
- {"dt": "2025-10-15T07:00:51.830475", "lvl": "target", "msg": "Linux runner-rgetuxoh-project-40964107-concurrent-0 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:51.831333", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:51.831741", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2025-10-15T07:00:51.831950", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2025-10-15T07:00:51.832205", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2025-10-15T07:00:51.832262", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:51.832502", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2025-10-15T07:00:51.832568", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2025-10-15T07:00:52.717786", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-10-15T07:00:52.717974", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:09) [common]"}
- {"dt": "2025-10-15T07:00:52.718052", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "9.37", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-10-15T07:00:52.718177", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:10) [common]"}
- {"dt": "2025-10-15T07:00:52.718259", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:19:50) [common]"}
- {"dt": "2025-10-15T07:00:52.718332", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-10-15T07:00:52.718391", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-10-15T07:00:52.718454", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-10-15T07:00:52.768590", "lvl": "input", "msg": ""}
- {"dt": "2025-10-15T07:00:52.768765", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:20:00)"}
- {"dt": "2025-10-15T07:00:52.768858", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2025-10-15T07:00:52.770015", "lvl": "target", "msg": "[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# "}
- {"dt": "2025-10-15T07:00:52.771935", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:52.772029", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:19:50) [common]"}
- {"dt": "2025-10-15T07:00:52.772130", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:52.772207", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:10) [common]"}
- {"dt": "2025-10-15T07:00:52.772284", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:52.772358", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:20:00) [common]"}
- {"dt": "2025-10-15T07:00:52.772420", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-10-15T07:00:52.772480", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-10-15T07:00:52.872687", "lvl": "input", "msg": "#"}
- {"dt": "2025-10-15T07:00:52.872851", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:20:00)"}
- {"dt": "2025-10-15T07:00:52.873452", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# #"}
- {"dt": "2025-10-15T07:00:52.875178", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-10-15T07:00:52.875251", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-10-15T07:00:52.975475", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-10-15T07:00:52.976186", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# export SHELL=/bin/sh"}
- {"dt": "2025-10-15T07:00:52.978061", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-10-15T07:00:53.078323", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-10-15T07:00:53.079236", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# . /lava-1/environment"}
- {"dt": "2025-10-15T07:00:53.081567", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-10-15T07:00:53.181843", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-10-15T07:00:53.181985", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-10-15T07:00:53.182742", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# /lava-1/bin/lava-test-runne\rer /lava-1/0"}
- {"dt": "2025-10-15T07:00:53.249373", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_commands"}
- {"dt": "2025-10-15T07:00:53.249566", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_commands"}
- {"dt": "2025-10-15T07:00:53.250808", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2025-10-15T07:00:53.253818", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-10-15T07:00:53.257640", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-10-15T07:00:53.257743", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2025-10-15T07:00:53.257817", "lvl": "target", "msg": "+ lava-test-case command --shell cd /opt/ltp/ && ./runltp -s overcommit_memory"}
- {"dt": "2025-10-15T07:00:53.258068", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2025-10-15T07:00:53.258143", "lvl": "info", "msg": "Starting test lava.0_commands (1_1.1.3.1)"}
- {"dt": "2025-10-15T07:00:53.258249", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-10-15T07:00:53.258780", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTTC command>"}
- {"dt": "2025-10-15T07:00:53.259011", "lvl": "debug", "msg": "Received signal: <STARTTC> command"}
- {"dt": "2025-10-15T07:00:53.275973", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-10-15T07:00:53.276113", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-10-15T07:00:53.276406", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-10-15T07:00:53.276486", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-10-15T07:00:53.297808", "lvl": "target", "msg": "INFO: creating /opt/ltp/output directory"}
- {"dt": "2025-10-15T07:00:53.301876", "lvl": "target", "msg": "INFO: creating /opt/ltp/results directory"}
- {"dt": "2025-10-15T07:00:53.329824", "lvl": "target", "msg": "INFO: no command files were provided. Executing following runtest scenario files:"}
- {"dt": "2025-10-15T07:00:53.330396", "lvl": "target", "msg": "syscalls fs fs_perms_simple dio mm ipc irq sched math nptl pty containers fs_bind controllers fcntl-locktests power_management_tests hugetlb commands hyperthreading can cpuhotplug net.ipv6_lib input cve crypto kernel_misc uevent watchqueue "}
- {"dt": "2025-10-15T07:00:53.330473", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.435408", "lvl": "target", "msg": "Checking for required user/group ids"}
- {"dt": "2025-10-15T07:00:53.435574", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.483129", "lvl": "target", "msg": "'root' user id and group found."}
- {"dt": "2025-10-15T07:00:53.483507", "lvl": "target", "msg": "'nobody' user id and group found."}
- {"dt": "2025-10-15T07:00:53.483772", "lvl": "target", "msg": "'bin' user id and group found."}
- {"dt": "2025-10-15T07:00:53.484054", "lvl": "target", "msg": "'daemon' user id and group found."}
- {"dt": "2025-10-15T07:00:53.484129", "lvl": "target", "msg": "Users group found."}
- {"dt": "2025-10-15T07:00:53.484399", "lvl": "target", "msg": "Sys group found."}
- {"dt": "2025-10-15T07:00:53.523441", "lvl": "target", "msg": "Required users/groups exist."}
- {"dt": "2025-10-15T07:00:53.527465", "lvl": "target", "msg": "If some fields are empty or look unusual you may have an old version."}
- {"dt": "2025-10-15T07:00:53.527933", "lvl": "target", "msg": "Compare to the current minimal requirements in Documentation/Changes."}
- {"dt": "2025-10-15T07:00:53.528005", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.538011", "lvl": "target", "msg": "/etc/os-release"}
- {"dt": "2025-10-15T07:00:53.541042", "lvl": "target", "msg": "PRETTY_NAME=\"Debian GNU/Linux 13 (trixie)\""}
- {"dt": "2025-10-15T07:00:53.541161", "lvl": "target", "msg": "NAME=\"Debian GNU/Linux\""}
- {"dt": "2025-10-15T07:00:53.541255", "lvl": "target", "msg": "VERSION_ID=\"13\""}
- {"dt": "2025-10-15T07:00:53.541341", "lvl": "target", "msg": "VERSION=\"13 (trixie)\""}
- {"dt": "2025-10-15T07:00:53.541425", "lvl": "target", "msg": "VERSION_CODENAME=trixie"}
- {"dt": "2025-10-15T07:00:53.541512", "lvl": "target", "msg": "DEBIAN_VERSION_FULL=13.1"}
- {"dt": "2025-10-15T07:00:53.541593", "lvl": "target", "msg": "ID=debian"}
- {"dt": "2025-10-15T07:00:53.541714", "lvl": "target", "msg": "HOME_URL=\"https://www.debian.org/\""}
- {"dt": "2025-10-15T07:00:53.541859", "lvl": "target", "msg": "SUPPORT_URL=\"https://www.debian.org/support\""}
- {"dt": "2025-10-15T07:00:53.542203", "lvl": "target", "msg": "BUG_REPORT_URL=\"https://bugs.debian.org/\""}
- {"dt": "2025-10-15T07:00:53.542762", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.542993", "lvl": "target", "msg": "uname:"}
- {"dt": "2025-10-15T07:00:53.545277", "lvl": "target", "msg": "Linux runner-rgetuxoh-project-40964107-concurrent-0 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686 GNU/Linux"}
- {"dt": "2025-10-15T07:00:53.545532", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.545777", "lvl": "target", "msg": "/proc/cmdline"}
- {"dt": "2025-10-15T07:00:53.548195", "lvl": "target", "msg": "console=ttyS0,115200 rootwait root=/dev/sda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2025-10-15T07:00:53.548445", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.575165", "lvl": "target", "msg": "Gnu C                  gcc (Debian 14.2.0-19) 14.2.0"}
- {"dt": "2025-10-15T07:00:53.581890", "lvl": "target", "msg": "<6>[   10.333493] ver_linux (327) used greatest stack depth: 5304 bytes left"}
- {"dt": "2025-10-15T07:00:53.582039", "lvl": "target", "msg": "Clang                 "}
- {"dt": "2025-10-15T07:00:53.655502", "lvl": "target", "msg": "util-linux             selinux)"}
- {"dt": "2025-10-15T07:00:53.667354", "lvl": "target", "msg": "mount                  util-linux 2.41 (libmount 2.41.0: selinux, smack, btrfs, verity, namespaces, idmapping, fd-based-mount, statmount, statx, assert, debug)"}
- {"dt": "2025-10-15T07:00:53.676371", "lvl": "target", "msg": "modutils               34.2"}
- {"dt": "2025-10-15T07:00:53.708377", "lvl": "target", "msg": "btrfs                  v6.14"}
- {"dt": "2025-10-15T07:00:53.724060", "lvl": "target", "msg": "e2fsprogs              1.47.2"}
- {"dt": "2025-10-15T07:00:53.750663", "lvl": "target", "msg": "ntfs                   v2022.10.3 (libntfs-3g)"}
- {"dt": "2025-10-15T07:00:53.765981", "lvl": "target", "msg": "vfat                   4.2"}
- {"dt": "2025-10-15T07:00:53.780954", "lvl": "target", "msg": "xfs                    6.13.0"}
- {"dt": "2025-10-15T07:00:53.843225", "lvl": "target", "msg": "Linux C Library        GNU C Library (Debian GLIBC 2.41-12) stable release version 2.41."}
- {"dt": "2025-10-15T07:00:53.862650", "lvl": "target", "msg": "Dynamic linker (ldd)   2.41"}
- {"dt": "2025-10-15T07:00:53.885666", "lvl": "target", "msg": "Procps                 4.0.4"}
- {"dt": "2025-10-15T07:00:53.898813", "lvl": "target", "msg": "Net-tools              2.10"}
- {"dt": "2025-10-15T07:00:53.910458", "lvl": "target", "msg": "iproute2               1.5.0"}
- {"dt": "2025-10-15T07:00:53.920108", "lvl": "target", "msg": "iputils                20240905"}
- {"dt": "2025-10-15T07:00:53.932862", "lvl": "target", "msg": "ethtool                6.14.2"}
- {"dt": "2025-10-15T07:00:53.942837", "lvl": "target", "msg": "Kbd                    loadkeys:"}
- {"dt": "2025-10-15T07:00:53.961848", "lvl": "target", "msg": "Sh-utils               9.7"}
- {"dt": "2025-10-15T07:00:53.962750", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.970826", "lvl": "target", "msg": "Modules Loaded         "}
- {"dt": "2025-10-15T07:00:53.970929", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:53.971023", "lvl": "target", "msg": "cpuinfo:"}
- {"dt": "2025-10-15T07:00:54.008219", "lvl": "target", "msg": "Architecture:                i686"}
- {"dt": "2025-10-15T07:00:54.008384", "lvl": "target", "msg": "  CPU op-mode(s):            32-bit"}
- {"dt": "2025-10-15T07:00:54.008702", "lvl": "target", "msg": "  Address sizes:             36 bits physical, 0 bits virtual"}
- {"dt": "2025-10-15T07:00:54.009065", "lvl": "target", "msg": "  Byte Order:                Little Endian"}
- {"dt": "2025-10-15T07:00:54.009159", "lvl": "target", "msg": "CPU(s):                      2"}
- {"dt": "2025-10-15T07:00:54.009231", "lvl": "target", "msg": "  On-line CPU(s) list:       0,1"}
- {"dt": "2025-10-15T07:00:54.009497", "lvl": "target", "msg": "Vendor ID:                   GenuineIntel"}
- {"dt": "2025-10-15T07:00:54.009901", "lvl": "target", "msg": "  Model name:                Genuine Intel(R) CPU           T2600  @ 2.16GHz"}
- {"dt": "2025-10-15T07:00:54.009984", "lvl": "target", "msg": "    CPU family:              6"}
- {"dt": "2025-10-15T07:00:54.010295", "lvl": "target", "msg": "    Model:                   14"}
- {"dt": "2025-10-15T07:00:54.010358", "lvl": "target", "msg": "    Thread(s) per core:      1"}
- {"dt": "2025-10-15T07:00:54.010425", "lvl": "target", "msg": "    Core(s) per socket:      2"}
- {"dt": "2025-10-15T07:00:54.010696", "lvl": "target", "msg": "    Socket(s):               1"}
- {"dt": "2025-10-15T07:00:54.010766", "lvl": "target", "msg": "    Stepping:                8"}
- {"dt": "2025-10-15T07:00:54.010856", "lvl": "target", "msg": "    BogoMIPS:                6787.18"}
- {"dt": "2025-10-15T07:00:54.012006", "lvl": "target", "msg": "    Flags:                   fpu de pse tsc msr pae mce cx8 apic sep mtrr pge mc"}
- {"dt": "2025-10-15T07:00:54.013131", "lvl": "target", "msg": "                             a cmov clflush acpi mmx fxsr sse sse2 ht nx constan"}
- {"dt": "2025-10-15T07:00:54.013410", "lvl": "target", "msg": "                             t_tsc rep_good cpuid pni monitor hypervisor"}
- {"dt": "2025-10-15T07:00:54.013481", "lvl": "target", "msg": "Caches (sum of all):         "}
- {"dt": "2025-10-15T07:00:54.013811", "lvl": "target", "msg": "  L1d:                       64 KiB (2 instances)"}
- {"dt": "2025-10-15T07:00:54.013888", "lvl": "target", "msg": "  L1i:                       64 KiB (2 instances)"}
- {"dt": "2025-10-15T07:00:54.014165", "lvl": "target", "msg": "  L2:                        8 MiB (2 instances)"}
- {"dt": "2025-10-15T07:00:54.014234", "lvl": "target", "msg": "  L3:                        16 MiB (1 instance)"}
- {"dt": "2025-10-15T07:00:54.014324", "lvl": "target", "msg": "Vulnerabilities:             "}
- {"dt": "2025-10-15T07:00:54.014639", "lvl": "target", "msg": "  Gather data sampling:      Not affected"}
- {"dt": "2025-10-15T07:00:54.014894", "lvl": "target", "msg": "  Ghostwrite:                Not affected"}
- {"dt": "2025-10-15T07:00:54.014954", "lvl": "target", "msg": "  Indirect target selection: Vulnerable"}
- {"dt": "2025-10-15T07:00:54.015234", "lvl": "target", "msg": "  Itlb multihit:             Processor vulnerable"}
- {"dt": "2025-10-15T07:00:54.015296", "lvl": "target", "msg": "  L1tf:                      Vulnerable"}
- {"dt": "2025-10-15T07:00:54.015660", "lvl": "target", "msg": "  Mds:                       Vulnerable: Clear CPU buffers attempted, no microco"}
- {"dt": "2025-10-15T07:00:54.015834", "lvl": "target", "msg": "                             de; SMT Host state unknown"}
- {"dt": "2025-10-15T07:00:54.016168", "lvl": "target", "msg": "  Meltdown:                  Vulnerable"}
- {"dt": "2025-10-15T07:00:54.016232", "lvl": "target", "msg": "  Mmio stale data:           Not affected"}
- {"dt": "2025-10-15T07:00:54.016482", "lvl": "target", "msg": "  Old microcode:             Not affected"}
- {"dt": "2025-10-15T07:00:54.016544", "lvl": "target", "msg": "  Reg file data sampling:    Not affected"}
- {"dt": "2025-10-15T07:00:54.016844", "lvl": "target", "msg": "  Retbleed:                  Not affected"}
- {"dt": "2025-10-15T07:00:54.016905", "lvl": "target", "msg": "  Spec rstack overflow:      Not affected"}
- {"dt": "2025-10-15T07:00:54.017145", "lvl": "target", "msg": "  Spec store bypass:         Not affected"}
- {"dt": "2025-10-15T07:00:54.017438", "lvl": "target", "msg": "  Spectre v1:                Mitigation; usercopy/swapgs barriers and __user poi"}
- {"dt": "2025-10-15T07:00:54.017508", "lvl": "target", "msg": "                             nter sanitization"}
- {"dt": "2025-10-15T07:00:54.017962", "lvl": "target", "msg": "  Spectre v2:                Mitigation; Retpolines; STIBP disabled; RSB filling"}
- {"dt": "2025-10-15T07:00:54.018210", "lvl": "target", "msg": "                             ; PBRSB-eIBRS Not affected; BHI Retpoline"}
- {"dt": "2025-10-15T07:00:54.018273", "lvl": "target", "msg": "  Srbds:                     Not affected"}
- {"dt": "2025-10-15T07:00:54.018519", "lvl": "target", "msg": "  Tsa:                       Not affected"}
- {"dt": "2025-10-15T07:00:54.018589", "lvl": "target", "msg": "  Tsx async abort:           Not affected"}
- {"dt": "2025-10-15T07:00:54.019018", "lvl": "target", "msg": "  Vmscape:                   Not affected"}
- {"dt": "2025-10-15T07:00:54.021386", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.021657", "lvl": "target", "msg": "free reports:"}
- {"dt": "2025-10-15T07:00:54.029069", "lvl": "target", "msg": "               total        used        free      shared  buff/cache   available"}
- {"dt": "2025-10-15T07:00:54.030200", "lvl": "target", "msg": "Mem:          872452       44320      753484         480       91084      828132"}
- {"dt": "2025-10-15T07:00:54.030637", "lvl": "target", "msg": "Swap:              0           0           0"}
- {"dt": "2025-10-15T07:00:54.031450", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.031734", "lvl": "target", "msg": "memory (/proc/meminfo):"}
- {"dt": "2025-10-15T07:00:54.033891", "lvl": "target", "msg": "MemTotal:         872452 kB"}
- {"dt": "2025-10-15T07:00:54.033978", "lvl": "target", "msg": "MemFree:          753484 kB"}
- {"dt": "2025-10-15T07:00:54.034067", "lvl": "target", "msg": "MemAvailable:     828268 kB"}
- {"dt": "2025-10-15T07:00:54.034169", "lvl": "target", "msg": "Buffers:            4024 kB"}
- {"dt": "2025-10-15T07:00:54.034252", "lvl": "target", "msg": "Cached:            82772 kB"}
- {"dt": "2025-10-15T07:00:54.034367", "lvl": "target", "msg": "SwapCached:            0 kB"}
- {"dt": "2025-10-15T07:00:54.034436", "lvl": "target", "msg": "Active:            22364 kB"}
- {"dt": "2025-10-15T07:00:54.034497", "lvl": "target", "msg": "Inactive:          77052 kB"}
- {"dt": "2025-10-15T07:00:54.034569", "lvl": "target", "msg": "Active(anon):        304 kB"}
- {"dt": "2025-10-15T07:00:54.034642", "lvl": "target", "msg": "Inactive(anon):    12744 kB"}
- {"dt": "2025-10-15T07:00:54.034713", "lvl": "target", "msg": "Active(file):      22060 kB"}
- {"dt": "2025-10-15T07:00:54.034789", "lvl": "target", "msg": "Inactive(file):    64308 kB"}
- {"dt": "2025-10-15T07:00:54.035049", "lvl": "target", "msg": "Unevictable:           0 kB"}
- {"dt": "2025-10-15T07:00:54.035117", "lvl": "target", "msg": "Mlocked:               0 kB"}
- {"dt": "2025-10-15T07:00:54.035181", "lvl": "target", "msg": "SwapTotal:             0 kB"}
- {"dt": "2025-10-15T07:00:54.035239", "lvl": "target", "msg": "SwapFree:              0 kB"}
- {"dt": "2025-10-15T07:00:54.035297", "lvl": "target", "msg": "Dirty:               988 kB"}
- {"dt": "2025-10-15T07:00:54.035543", "lvl": "target", "msg": "Writeback:             0 kB"}
- {"dt": "2025-10-15T07:00:54.035607", "lvl": "target", "msg": "AnonPages:         12584 kB"}
- {"dt": "2025-10-15T07:00:54.035865", "lvl": "target", "msg": "Mapped:            21868 kB"}
- {"dt": "2025-10-15T07:00:54.035918", "lvl": "target", "msg": "Shmem:               480 kB"}
- {"dt": "2025-10-15T07:00:54.035975", "lvl": "target", "msg": "KReclaimable:       4288 kB"}
- {"dt": "2025-10-15T07:00:54.036035", "lvl": "target", "msg": "Slab:              11168 kB"}
- {"dt": "2025-10-15T07:00:54.036301", "lvl": "target", "msg": "SReclaimable:       4288 kB"}
- {"dt": "2025-10-15T07:00:54.036354", "lvl": "target", "msg": "SUnreclaim:         6880 kB"}
- {"dt": "2025-10-15T07:00:54.036409", "lvl": "target", "msg": "KernelStack:         832 kB"}
- {"dt": "2025-10-15T07:00:54.036465", "lvl": "target", "msg": "PageTables:         1132 kB"}
- {"dt": "2025-10-15T07:00:54.036704", "lvl": "target", "msg": "SecPageTables:         0 kB"}
- {"dt": "2025-10-15T07:00:54.036765", "lvl": "target", "msg": "NFS_Unstable:          0 kB"}
- {"dt": "2025-10-15T07:00:54.037017", "lvl": "target", "msg": "Bounce:                0 kB"}
- {"dt": "2025-10-15T07:00:54.037070", "lvl": "target", "msg": "WritebackTmp:          0 kB"}
- {"dt": "2025-10-15T07:00:54.037134", "lvl": "target", "msg": "CommitLimit:      436224 kB"}
- {"dt": "2025-10-15T07:00:54.037193", "lvl": "target", "msg": "Committed_AS:      38256 kB"}
- {"dt": "2025-10-15T07:00:54.037242", "lvl": "target", "msg": "VmallocTotal:     122880 kB"}
- {"dt": "2025-10-15T07:00:54.037299", "lvl": "target", "msg": "VmallocUsed:         204 kB"}
- {"dt": "2025-10-15T07:00:54.037355", "lvl": "target", "msg": "VmallocChunk:          0 kB"}
- {"dt": "2025-10-15T07:00:54.037412", "lvl": "target", "msg": "Percpu:              256 kB"}
- {"dt": "2025-10-15T07:00:54.037644", "lvl": "target", "msg": "Balloon:               0 kB"}
- {"dt": "2025-10-15T07:00:54.037697", "lvl": "target", "msg": "HugePages_Total:       0"}
- {"dt": "2025-10-15T07:00:54.037753", "lvl": "target", "msg": "HugePages_Free:        0"}
- {"dt": "2025-10-15T07:00:54.037799", "lvl": "target", "msg": "HugePages_Rsvd:        0"}
- {"dt": "2025-10-15T07:00:54.037853", "lvl": "target", "msg": "HugePages_Surp:        0"}
- {"dt": "2025-10-15T07:00:54.037909", "lvl": "target", "msg": "Hugepagesize:       4096 kB"}
- {"dt": "2025-10-15T07:00:54.038140", "lvl": "target", "msg": "Hugetlb:               0 kB"}
- {"dt": "2025-10-15T07:00:54.038194", "lvl": "target", "msg": "DirectMap4k:       32760 kB"}
- {"dt": "2025-10-15T07:00:54.038241", "lvl": "target", "msg": "DirectMap4M:      872448 kB"}
- {"dt": "2025-10-15T07:00:54.038743", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.038976", "lvl": "target", "msg": "available filesystems:"}
- {"dt": "2025-10-15T07:00:54.050070", "lvl": "target", "msg": "9p autofs bdev binfmt_misc cgroup cgroup2 debugfs devpts devtmpfs ext2 ext3 ext4 hugetlbfs iso9660 mqueue msdos nfs nfs4 pipefs proc ramfs rpc_pipefs selinuxfs sockfs sysfs tmpfs tracefs vfat"}
- {"dt": "2025-10-15T07:00:54.050186", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.050260", "lvl": "target", "msg": "mounted filesystems (/proc/mounts):"}
- {"dt": "2025-10-15T07:00:54.052759", "lvl": "target", "msg": "/dev/root / ext4 rw,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.052965", "lvl": "target", "msg": "devtmpfs /dev devtmpfs rw,relatime,size=435788k,nr_inodes=108947,mode=755 0 0"}
- {"dt": "2025-10-15T07:00:54.053155", "lvl": "target", "msg": "proc /proc proc rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.053303", "lvl": "target", "msg": "sysfs /sys sysfs rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.053653", "lvl": "target", "msg": "selinuxfs /sys/fs/selinux selinuxfs rw,nosuid,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.053753", "lvl": "target", "msg": "tmpfs /dev/shm tmpfs rw,nosuid,nodev 0 0"}
- {"dt": "2025-10-15T07:00:54.054133", "lvl": "target", "msg": "devpts /dev/pts devpts rw,nosuid,noexec,relatime,gid=5,mode=600,ptmxmode=000 0 0"}
- {"dt": "2025-10-15T07:00:54.054432", "lvl": "target", "msg": "tmpfs /run tmpfs rw,nosuid,nodev,size=174492k,nr_inodes=819200,mode=755 0 0"}
- {"dt": "2025-10-15T07:00:54.054975", "lvl": "target", "msg": "cgroup2 /sys/fs/cgroup cgroup2 rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot 0 0"}
- {"dt": "2025-10-15T07:00:54.055303", "lvl": "target", "msg": "systemd-1 /proc/sys/fs/binfmt_misc autofs rw,relatime,fd=36,pgrp=1,timeout=0,minproto=5,maxproto=5,direct 0 0"}
- {"dt": "2025-10-15T07:00:54.055653", "lvl": "target", "msg": "mqueue /dev/mqueue mqueue rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.055742", "lvl": "target", "msg": "hugetlbfs /dev/hugepages hugetlbfs rw,nosuid,nodev,relatime,pagesize=4M 0 0"}
- {"dt": "2025-10-15T07:00:54.056043", "lvl": "target", "msg": "debugfs /sys/kernel/debug debugfs rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.056107", "lvl": "target", "msg": "tracefs /sys/kernel/tracing tracefs rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.056290", "lvl": "target", "msg": "tmpfs /run/lock tmpfs rw,nosuid,nodev,noexec,relatime,size=5120k 0 0"}
- {"dt": "2025-10-15T07:00:54.056602", "lvl": "target", "msg": "tmpfs /tmp tmpfs rw,nosuid,nodev,size=436228k,nr_inodes=1048576 0 0"}
- {"dt": "2025-10-15T07:00:54.056900", "lvl": "target", "msg": "tmpfs /run/credentials/systemd-journald.service tmpfs ro,nosuid,nodev,noexec,relatime,nosymfollow,size=1024k,nr_inodes=1024,mode=700,noswap 0 0"}
- {"dt": "2025-10-15T07:00:54.057145", "lvl": "target", "msg": "binfmt_misc /proc/sys/fs/binfmt_misc binfmt_misc rw,nosuid,nodev,noexec,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.057289", "lvl": "target", "msg": "sunrpc /run/rpc_pipefs rpc_pipefs rw,relatime 0 0"}
- {"dt": "2025-10-15T07:00:54.057809", "lvl": "target", "msg": "tmpfs /run/credentials/serial-getty@ttyS0.service tmpfs ro,nosuid,nodev,noexec,relatime,nosymfollow,size=1024k,nr_inodes=1024,mode=700,noswap 0 0"}
- {"dt": "2025-10-15T07:00:54.058089", "lvl": "target", "msg": "tmpfs /run/credentials/getty@tty1.service tmpfs ro,nosuid,nodev,noexec,relatime,nosymfollow,size=1024k,nr_inodes=1024,mode=700,noswap 0 0"}
- {"dt": "2025-10-15T07:00:54.058375", "lvl": "target", "msg": "tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=87244k,nr_inodes=21811,mode=700 0 0"}
- {"dt": "2025-10-15T07:00:54.058741", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.058978", "lvl": "target", "msg": "mounted filesystems (df):"}
- {"dt": "2025-10-15T07:00:54.075843", "lvl": "target", "msg": "Filesystem     Type      Size  Used Avail Use% Mounted on"}
- {"dt": "2025-10-15T07:00:54.076012", "lvl": "target", "msg": "/dev/root      ext4      2.5G  1.1G  1.3G  47% /"}
- {"dt": "2025-10-15T07:00:54.076412", "lvl": "target", "msg": "devtmpfs       devtmpfs  426M     0  426M   0% /dev"}
- {"dt": "2025-10-15T07:00:54.076491", "lvl": "target", "msg": "tmpfs          tmpfs     426M     0  426M   0% /dev/shm"}
- {"dt": "2025-10-15T07:00:54.076914", "lvl": "target", "msg": "tmpfs          tmpfs     171M  392K  171M   1% /run"}
- {"dt": "2025-10-15T07:00:54.077012", "lvl": "target", "msg": "tmpfs          tmpfs     5.0M     0  5.0M   0% /run/lock"}
- {"dt": "2025-10-15T07:00:54.077125", "lvl": "target", "msg": "tmpfs          tmpfs     427M   84K  426M   1% /tmp"}
- {"dt": "2025-10-15T07:00:54.077652", "lvl": "target", "msg": "tmpfs          tmpfs     1.0M     0  1.0M   0% /run/credentials/systemd-journald.service"}
- {"dt": "2025-10-15T07:00:54.077913", "lvl": "target", "msg": "tmpfs          tmpfs     1.0M     0  1.0M   0% /run/credentials/serial-getty@ttyS0.service"}
- {"dt": "2025-10-15T07:00:54.078045", "lvl": "target", "msg": "tmpfs          tmpfs     1.0M     0  1.0M   0% /run/credentials/getty@tty1.service"}
- {"dt": "2025-10-15T07:00:54.078364", "lvl": "target", "msg": "tmpfs          tmpfs      86M  4.0K   86M   1% /run/user/0"}
- {"dt": "2025-10-15T07:00:54.078632", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.078866", "lvl": "target", "msg": "tainted (/proc/sys/kernel/tainted):"}
- {"dt": "2025-10-15T07:00:54.081658", "lvl": "target", "msg": "0"}
- {"dt": "2025-10-15T07:00:54.082408", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.083593", "lvl": "target", "msg": "Secure Boot: EFI variables not supported on SUT"}
- {"dt": "2025-10-15T07:00:54.111039", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.111417", "lvl": "target", "msg": "Lockdown (/sys/kernel/security/lockdown):"}
- {"dt": "2025-10-15T07:00:54.114724", "lvl": "target", "msg": "cat: /sys/kernel/security/lockdown: No such file or directory"}
- {"dt": "2025-10-15T07:00:54.115175", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.115432", "lvl": "target", "msg": "AppArmor: disabled"}
- {"dt": "2025-10-15T07:00:54.115491", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.116877", "lvl": "target", "msg": "SELinux mode: unknown"}
- {"dt": "2025-10-15T07:00:54.117635", "lvl": "target", "msg": "no big block device was specified on commandline."}
- {"dt": "2025-10-15T07:00:54.117969", "lvl": "target", "msg": "Tests which require a big block device are disabled."}
- {"dt": "2025-10-15T07:00:54.118033", "lvl": "target", "msg": "You can specify it with option -z"}
- {"dt": "2025-10-15T07:00:54.119357", "lvl": "target", "msg": "COMMAND:    /opt/ltp/bin/ltp-pan   -e -S   -a 246     -n 246 -p -f /tmp/ltp-TNrYyW4buj/alltests -l /opt/ltp/results/LTP_RUN_ON-2025_10_15-07h_00m_51s.log  -C /opt/ltp/output/LTP_RUN_ON-2025_10_15-07h_00m_51s.failed -T /opt/ltp/output/LTP_RUN_ON-2025_10_15-07h_00m_51s.tconf"}
- {"dt": "2025-10-15T07:00:54.119618", "lvl": "target", "msg": "INFO: Restricted to overcommit_memory"}
- {"dt": "2025-10-15T07:00:54.123704", "lvl": "target", "msg": "LOG File: /opt/ltp/results/LTP_RUN_ON-2025_10_15-07h_00m_51s.log"}
- {"dt": "2025-10-15T07:00:54.127862", "lvl": "target", "msg": "FAILED COMMAND File: /opt/ltp/output/LTP_RUN_ON-2025_10_15-07h_00m_51s.failed"}
- {"dt": "2025-10-15T07:00:54.131934", "lvl": "target", "msg": "TCONF COMMAND File: /opt/ltp/output/LTP_RUN_ON-2025_10_15-07h_00m_51s.tconf"}
- {"dt": "2025-10-15T07:00:54.132580", "lvl": "target", "msg": "Running tests......."}
- {"dt": "2025-10-15T07:00:54.144293", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.144441", "lvl": "target", "msg": "tag=overcommit_memory01 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.144555", "lvl": "target", "msg": "cmdline=\"overcommit_memory\""}
- {"dt": "2025-10-15T07:00:54.144661", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.144762", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.144884", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.145420", "lvl": "target", "msg": "<12>[   10.897001] LTP: starting overcommit_memory01 (overcommit_memory)"}
- {"dt": "2025-10-15T07:00:54.152605", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.155219", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.155649", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.156986", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.157352", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.159909", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.160187", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.160790", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.161246", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 50"}
- {"dt": "2025-10-15T07:00:54.161964", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.162417", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.163766", "lvl": "target", "msg": "<4>[   10.915326] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 814436352 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.164107", "lvl": "target", "msg": "<4>[   10.915746] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 814448640 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.164439", "lvl": "target", "msg": "<4>[   10.916089] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 814567424 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.164866", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 795344 kB failed"}
- {"dt": "2025-10-15T07:00:54.165141", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.165398", "lvl": "target", "msg": "<4>[   10.916995] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 450187264 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.165695", "lvl": "target", "msg": "<4>[   10.917270] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 450199552 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.166210", "lvl": "target", "msg": "<4>[   10.917632] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 450318336 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.166460", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 439632 kB failed"}
- {"dt": "2025-10-15T07:00:54.166698", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.167380", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 198836 kB successfully"}
- {"dt": "2025-10-15T07:00:54.167814", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.168143", "lvl": "target", "msg": "overcommit_memory.c:149: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 0"}
- {"dt": "2025-10-15T07:00:54.168897", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 376590 kB successfully"}
- {"dt": "2025-10-15T07:00:54.169203", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.169530", "lvl": "target", "msg": "<4>[   10.921005] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 1786785792 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.169868", "lvl": "target", "msg": "<4>[   10.921289] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 1786798080 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.170154", "lvl": "target", "msg": "<4>[   10.921671] __vm_enough_memory: pid: 436, comm: overcommit_memo, bytes: 1786916864 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.170461", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1744904 kB failed"}
- {"dt": "2025-10-15T07:00:54.170908", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.171232", "lvl": "target", "msg": "overcommit_memory.c:164: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 1"}
- {"dt": "2025-10-15T07:00:54.171824", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 436226 kB successfully"}
- {"dt": "2025-10-15T07:00:54.172238", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.172602", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 872452 kB successfully"}
- {"dt": "2025-10-15T07:00:54.173008", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.173325", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 1744904 kB successfully"}
- {"dt": "2025-10-15T07:00:54.173620", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.174926", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.175162", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.175218", "lvl": "target", "msg": "passed   8"}
- {"dt": "2025-10-15T07:00:54.175269", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.175318", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.175365", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-10-15T07:00:54.175427", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.177453", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.177532", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.177855", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=0 corefile=no"}
- {"dt": "2025-10-15T07:00:54.177950", "lvl": "target", "msg": "cutime=1 cstime=1"}
- {"dt": "2025-10-15T07:00:54.178038", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.178483", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.178567", "lvl": "target", "msg": "tag=overcommit_memory02 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.178813", "lvl": "target", "msg": "cmdline=\"overcommit_memory -R 0\""}
- {"dt": "2025-10-15T07:00:54.178880", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.178935", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.179003", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.179197", "lvl": "target", "msg": "<12>[   10.930864] LTP: starting overcommit_memory02 (overcommit_memory -R 0)"}
- {"dt": "2025-10-15T07:00:54.183132", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.184387", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.184672", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.185088", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.185193", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.186856", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.187241", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.187604", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.187957", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 0"}
- {"dt": "2025-10-15T07:00:54.188405", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.188704", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.189560", "lvl": "target", "msg": "overcommit_memory.c:235: [1;34mTINFO: [0mCommitLimit is 0, Committed_AS is 38552"}
- {"dt": "2025-10-15T07:00:54.190118", "lvl": "target", "msg": "overcommit_memory.c:243: [1;33mTCONF: [0mSpecified overcommit_ratio 0 <= default 50, so it's possible for CommitLimit < Committed_AS and skip test"}
- {"dt": "2025-10-15T07:00:54.190826", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.191070", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.191136", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-10-15T07:00:54.191187", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.191243", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.191297", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-10-15T07:00:54.191360", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.192520", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.192593", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.192897", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=32 corefile=no"}
- {"dt": "2025-10-15T07:00:54.192954", "lvl": "target", "msg": "cutime=0 cstime=2"}
- {"dt": "2025-10-15T07:00:54.193003", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.193231", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.193319", "lvl": "target", "msg": "tag=overcommit_memory03 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.193395", "lvl": "target", "msg": "cmdline=\"overcommit_memory -R 30\""}
- {"dt": "2025-10-15T07:00:54.193467", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.193521", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.193797", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.193911", "lvl": "target", "msg": "<12>[   10.945614] LTP: starting overcommit_memory03 (overcommit_memory -R 30)"}
- {"dt": "2025-10-15T07:00:54.197592", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.198654", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.198981", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.199286", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.199559", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.201304", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.201569", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.202120", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.202392", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 30"}
- {"dt": "2025-10-15T07:00:54.203025", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.203358", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.204043", "lvl": "target", "msg": "<4>[   10.955667] __vm_enough_memory: pid: 440, comm: overcommit_memo, bytes: 457076736 not enough memory for the allocation"}
- {"dt": "2025-10-15T07:00:54.204512", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 446360 kB failed"}
- {"dt": "2025-10-15T07:00:54.204758", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.205040", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 265140 kB failed"}
- {"dt": "2025-10-15T07:00:54.205385", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.205941", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 111590 kB successfully"}
- {"dt": "2025-10-15T07:00:54.206413", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.206661", "lvl": "target", "msg": "overcommit_memory.c:149: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 0"}
- {"dt": "2025-10-15T07:00:54.207514", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 376464 kB successfully"}
- {"dt": "2025-10-15T07:00:54.207946", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.208214", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1744904 kB failed"}
- {"dt": "2025-10-15T07:00:54.208469", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.208770", "lvl": "target", "msg": "overcommit_memory.c:164: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 1"}
- {"dt": "2025-10-15T07:00:54.209399", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 436226 kB successfully"}
- {"dt": "2025-10-15T07:00:54.209654", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.210147", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 872452 kB successfully"}
- {"dt": "2025-10-15T07:00:54.210450", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.210874", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 1744904 kB successfully"}
- {"dt": "2025-10-15T07:00:54.211165", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.211873", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.212124", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.212216", "lvl": "target", "msg": "passed   8"}
- {"dt": "2025-10-15T07:00:54.212287", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.212350", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.212421", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-10-15T07:00:54.212483", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.213450", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.213723", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.213990", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=0 corefile=no"}
- {"dt": "2025-10-15T07:00:54.214057", "lvl": "target", "msg": "cutime=0 cstime=1"}
- {"dt": "2025-10-15T07:00:54.214140", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.214440", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.214502", "lvl": "target", "msg": "tag=overcommit_memory04 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.214776", "lvl": "target", "msg": "cmdline=\"overcommit_memory -R 80\""}
- {"dt": "2025-10-15T07:00:54.214837", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.214912", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.215003", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.215207", "lvl": "target", "msg": "<12>[   10.966898] LTP: starting overcommit_memory04 (overcommit_memory -R 80)"}
- {"dt": "2025-10-15T07:00:54.219198", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.220285", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.220643", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.220791", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.221012", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.222790", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.223143", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.223446", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.223717", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 80"}
- {"dt": "2025-10-15T07:00:54.224204", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.224514", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.225128", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1318816 kB failed"}
- {"dt": "2025-10-15T07:00:54.225507", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.225789", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 701368 kB failed"}
- {"dt": "2025-10-15T07:00:54.226062", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.226555", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 329704 kB successfully"}
- {"dt": "2025-10-15T07:00:54.226873", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.227182", "lvl": "target", "msg": "overcommit_memory.c:149: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 0"}
- {"dt": "2025-10-15T07:00:54.227826", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 376464 kB successfully"}
- {"dt": "2025-10-15T07:00:54.228127", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.228438", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1744904 kB failed"}
- {"dt": "2025-10-15T07:00:54.228676", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.229062", "lvl": "target", "msg": "overcommit_memory.c:164: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 1"}
- {"dt": "2025-10-15T07:00:54.229525", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 436226 kB successfully"}
- {"dt": "2025-10-15T07:00:54.229824", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.230213", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 872452 kB successfully"}
- {"dt": "2025-10-15T07:00:54.230581", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.231039", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 1744904 kB successfully"}
- {"dt": "2025-10-15T07:00:54.231301", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.232039", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.232275", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.232333", "lvl": "target", "msg": "passed   8"}
- {"dt": "2025-10-15T07:00:54.232395", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.232456", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.232531", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-10-15T07:00:54.232590", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.233553", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.233825", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.234076", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=0 corefile=no"}
- {"dt": "2025-10-15T07:00:54.234134", "lvl": "target", "msg": "cutime=0 cstime=2"}
- {"dt": "2025-10-15T07:00:54.234208", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.234443", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.234696", "lvl": "target", "msg": "tag=overcommit_memory05 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.234943", "lvl": "target", "msg": "cmdline=\"overcommit_memory -R 100\""}
- {"dt": "2025-10-15T07:00:54.235000", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.235059", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.235128", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.235340", "lvl": "target", "msg": "<12>[   10.987000] LTP: starting overcommit_memory05 (overcommit_memory -R 100)"}
- {"dt": "2025-10-15T07:00:54.239315", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.240124", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.240418", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.240880", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.241021", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.242518", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.242810", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.243300", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.243594", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 100"}
- {"dt": "2025-10-15T07:00:54.244133", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.244534", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.245350", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1667800 kB failed"}
- {"dt": "2025-10-15T07:00:54.245632", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.245996", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 875860 kB failed"}
- {"dt": "2025-10-15T07:00:54.246274", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.246869", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 416950 kB successfully"}
- {"dt": "2025-10-15T07:00:54.247282", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.247578", "lvl": "target", "msg": "overcommit_memory.c:149: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 0"}
- {"dt": "2025-10-15T07:00:54.248448", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 376464 kB successfully"}
- {"dt": "2025-10-15T07:00:54.248828", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.249222", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1744904 kB failed"}
- {"dt": "2025-10-15T07:00:54.249519", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.249817", "lvl": "target", "msg": "overcommit_memory.c:164: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 1"}
- {"dt": "2025-10-15T07:00:54.250420", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 436226 kB successfully"}
- {"dt": "2025-10-15T07:00:54.250687", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.251114", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 872452 kB successfully"}
- {"dt": "2025-10-15T07:00:54.251373", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.251951", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 1744904 kB successfully"}
- {"dt": "2025-10-15T07:00:54.252357", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.252907", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.253138", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.253199", "lvl": "target", "msg": "passed   8"}
- {"dt": "2025-10-15T07:00:54.253260", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.253321", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.253393", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-10-15T07:00:54.253449", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.254626", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.254896", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.255147", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=0 corefile=no"}
- {"dt": "2025-10-15T07:00:54.255203", "lvl": "target", "msg": "cutime=1 cstime=1"}
- {"dt": "2025-10-15T07:00:54.255263", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.255500", "lvl": "target", "msg": "<<<test_start>>>"}
- {"dt": "2025-10-15T07:00:54.255739", "lvl": "target", "msg": "tag=overcommit_memory06 stime=1760511652"}
- {"dt": "2025-10-15T07:00:54.255985", "lvl": "target", "msg": "cmdline=\"overcommit_memory -R 200\""}
- {"dt": "2025-10-15T07:00:54.256042", "lvl": "target", "msg": "contacts=\"\""}
- {"dt": "2025-10-15T07:00:54.256103", "lvl": "target", "msg": "analysis=exit"}
- {"dt": "2025-10-15T07:00:54.256176", "lvl": "target", "msg": "<<<test_output>>>"}
- {"dt": "2025-10-15T07:00:54.256417", "lvl": "target", "msg": "<12>[   11.008066] LTP: starting overcommit_memory06 (overcommit_memory -R 200)"}
- {"dt": "2025-10-15T07:00:54.258981", "lvl": "target", "msg": "incrementing stop"}
- {"dt": "2025-10-15T07:00:54.260480", "lvl": "target", "msg": "tst_kernel.c:90: [1;34mTINFO: [0muname.machine=i686 kernel is 32bit"}
- {"dt": "2025-10-15T07:00:54.261542", "lvl": "target", "msg": "tst_test.c:2021: [1;34mTINFO: [0mLTP version: 20250930"}
- {"dt": "2025-10-15T07:00:54.262141", "lvl": "target", "msg": "tst_test.c:2024: [1;34mTINFO: [0mTested kernel: 6.18.0-rc1 #1 SMP PREEMPT_DYNAMIC @1760301756 i686"}
- {"dt": "2025-10-15T07:00:54.262459", "lvl": "target", "msg": "tst_kconfig.c:71: [1;34mTINFO: [0mCouldn't locate kernel config!"}
- {"dt": "2025-10-15T07:00:54.262835", "lvl": "target", "msg": "tst_test.c:1842: [1;34mTINFO: [0mOverall timeout per run is 0h 00m 30s"}
- {"dt": "2025-10-15T07:00:54.264414", "lvl": "target", "msg": "overcommit_memory.c:103: [1;34mTINFO: [0mMemTotal is 872452 kB"}
- {"dt": "2025-10-15T07:00:54.264912", "lvl": "target", "msg": "overcommit_memory.c:105: [1;34mTINFO: [0mSwapTotal is 0 kB"}
- {"dt": "2025-10-15T07:00:54.265224", "lvl": "target", "msg": "overcommit_memory.c:109: [1;34mTINFO: [0mCommitLimit is 436224 kB"}
- {"dt": "2025-10-15T07:00:54.265314", "lvl": "target", "msg": "overcommit_memory.c:122: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_ratio to 200"}
- {"dt": "2025-10-15T07:00:54.265822", "lvl": "target", "msg": "overcommit_memory.c:125: [1;34mTINFO: [0mTotalBatchSize is 3408 kB"}
- {"dt": "2025-10-15T07:00:54.266296", "lvl": "target", "msg": "overcommit_memory.c:131: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 2"}
- {"dt": "2025-10-15T07:00:54.267076", "lvl": "target", "msg": "overcommit_memory.c:139: [1;33mTCONF: [0mSkipping large allocation tests due to address space limits"}
- {"dt": "2025-10-15T07:00:54.267652", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 853176 kB successfully"}
- {"dt": "2025-10-15T07:00:54.267933", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.268263", "lvl": "target", "msg": "overcommit_memory.c:149: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 0"}
- {"dt": "2025-10-15T07:00:54.268984", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 376464 kB successfully"}
- {"dt": "2025-10-15T07:00:54.269267", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.269543", "lvl": "target", "msg": "overcommit_memory.c:186: [1;34mTINFO: [0mmalloc 1744904 kB failed"}
- {"dt": "2025-10-15T07:00:54.269789", "lvl": "target", "msg": "overcommit_memory.c:207: [1;32mTPASS: [0malloc failed as expected"}
- {"dt": "2025-10-15T07:00:54.270104", "lvl": "target", "msg": "overcommit_memory.c:164: [1;34mTINFO: [0mSetting /proc/sys/vm/overcommit_memory to 1"}
- {"dt": "2025-10-15T07:00:54.270545", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 436226 kB successfully"}
- {"dt": "2025-10-15T07:00:54.270892", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.271188", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 872452 kB successfully"}
- {"dt": "2025-10-15T07:00:54.271482", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.271780", "lvl": "target", "msg": "overcommit_memory.c:182: [1;34mTINFO: [0mmalloc 1744904 kB successfully"}
- {"dt": "2025-10-15T07:00:54.272021", "lvl": "target", "msg": "overcommit_memory.c:201: [1;32mTPASS: [0malloc passed as expected"}
- {"dt": "2025-10-15T07:00:54.272810", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.273046", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-10-15T07:00:54.273103", "lvl": "target", "msg": "passed   6"}
- {"dt": "2025-10-15T07:00:54.273179", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-10-15T07:00:54.273241", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-10-15T07:00:54.273300", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-10-15T07:00:54.273373", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-10-15T07:00:54.274460", "lvl": "target", "msg": "<<<execution_status>>>"}
- {"dt": "2025-10-15T07:00:54.274520", "lvl": "target", "msg": "initiation_status=\"ok\""}
- {"dt": "2025-10-15T07:00:54.274814", "lvl": "target", "msg": "duration=0 termination_type=exited termination_id=0 corefile=no"}
- {"dt": "2025-10-15T07:00:54.274872", "lvl": "target", "msg": "cutime=0 cstime=2"}
- {"dt": "2025-10-15T07:00:54.274948", "lvl": "target", "msg": "<<<test_end>>>"}
- {"dt": "2025-10-15T07:00:54.276365", "lvl": "target", "msg": "INFO: ltp-pan reported all tests PASS"}
- {"dt": "2025-10-15T07:00:54.276443", "lvl": "target", "msg": "LTP Version: 20250930"}
- {"dt": "2025-10-15T07:00:54.278997", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.279262", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-10-15T07:00:54.279333", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.279426", "lvl": "target", "msg": "            Done executing testcases."}
- {"dt": "2025-10-15T07:00:54.279497", "lvl": "target", "msg": "            LTP Version:  20250930"}
- {"dt": "2025-10-15T07:00:54.279761", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-10-15T07:00:54.279819", "lvl": "target", "msg": ""}
- {"dt": "2025-10-15T07:00:54.280384", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-10-15T07:00:54.280485", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-10-15T07:00:54.280835", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-10-15T07:00:54.280904", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-10-15T07:00:54.286865", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDTC command>"}
- {"dt": "2025-10-15T07:00:54.287161", "lvl": "debug", "msg": "Received signal: <ENDTC> command"}
- {"dt": "2025-10-15T07:00:54.292948", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=command RESULT=pass>"}
- {"dt": "2025-10-15T07:00:54.293219", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=command RESULT=pass"}
- {"dt": "2025-10-15T07:00:54.293326", "lvl": "results", "msg": {"definition": "0_commands", "case": "command", "result": "pass", "starttc": ! "787", "endtc": ! "1305"}}
- {"dt": "2025-10-15T07:00:54.293829", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-10-15T07:00:54.293901", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2025-10-15T07:00:54.294136", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2025-10-15T07:00:54.294213", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-10-15T07:00:54.294274", "lvl": "info", "msg": "Ending test lava.0_commands (1_1.1.3.1), duration 1.04"}
- {"dt": "2025-10-15T07:00:54.294378", "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 'cd /opt/ltp/ && ./runltp -s overcommit_memory'"]}}, "path": "command", "duration": "1.04", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-10-15T07:00:54.295542", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-10-15T07:00:54.295772", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-10-15T07:00:54.295862", "lvl": "debug", "msg": "command: pass\n"}
- {"dt": "2025-10-15T07:00:54.295950", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:02) [common]"}
- {"dt": "2025-10-15T07:00:54.296032", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:02) [common]"}
- {"dt": "2025-10-15T07:00:54.296123", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:54.296215", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-10-15T07:00:54.296297", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-10-15T07:00:54.296376", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-10-15T07:00:54.296511", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-10-15T07:00:54.296929", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-10-15T07:00:54.297184", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-10-15T07:00:55.297205", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-10-15T07:00:55.297402", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-10-15T07:00:55.397637", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-10-15T07:00:55.398012", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-10-15T07:00:55.398116", "lvl": "target", "msg": "[?2004hroot@runner-rgetuxoh-project-40964107-concurrent-0:~# poweroff"}
- {"dt": "2025-10-15T07:00:55.498395", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-10-15T07:00:55.498541", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-10-15T07:00:55.498655", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-10-15T07:00:55.498776", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-10-15T07:00:55.498902", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/kernel/bzImage"}
- {"dt": "2025-10-15T07:00:55.499821", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs/https___storage.tuxboot.com_debian_trixie_i386_rootfs.ext4"}
- {"dt": "2025-10-15T07:00:55.589386", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.modules/modules.tar"}
- {"dt": "2025-10-15T07:00:55.589674", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1/deployimages-2km0n1p2/rootfs.overlay-00/ltp.tar"}
- {"dt": "2025-10-15T07:00:55.641334", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-ejxo_ip1-1"}
- {"dt": "2025-10-15T07:00:55.642615", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-10-15T07:00:55.642693", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
