- {"dt": "2025-03-25T10:29:10.861563", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0173"}
- {"dt": "2025-03-25T10:29:10.861647", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-03-25T10:29:10.861719", "lvl": "info", "msg": "Start time: 2025-03-25 10:29:10.861713+00:00 (UTC)"}
- {"dt": "2025-03-25T10:29:10.862504", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/Image.gz exists"}
- {"dt": "2025-03-25T10:29:11.161888", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2025-03-25T10:29:11.782130", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4.xz exists"}
- {"dt": "2025-03-25T10:29:11.782298", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/kselftest.tar.xz exists"}
- {"dt": "2025-03-25T10:29:11.892312", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2025-03-25T10:29:12.418207", "lvl": "debug", "msg": "Validating that https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/modules.tar.xz exists"}
- {"dt": "2025-03-25T10:29:12.526889", "lvl": "debug", "msg": "Using GET because HEAD is not supported properly"}
- {"dt": "2025-03-25T10:29:13.064634", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:9.2.1+ds-1, host architecture: amd64"}
- {"dt": "2025-03-25T10:29:13.064870", "lvl": "warning", "msg": "Missing image arg for rootfs.kselftest"}
- {"dt": "2025-03-25T10:29:13.064956", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2025-03-25T10:29:13.065099", "lvl": "info", "msg": "validate duration: 2.20"}
- {"dt": "2025-03-25T10:29:13.065166", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-03-25T10:29:13.065289", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.065371", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.065581", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np"}
- {"dt": "2025-03-25T10:29:13.065715", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin"}
- {"dt": "2025-03-25T10:29:13.065821", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/tests"}
- {"dt": "2025-03-25T10:29:13.065913", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/results"}
- {"dt": "2025-03-25T10:29:13.065992", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-add-keys"}
- {"dt": "2025-03-25T10:29:13.066122", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-add-sources"}
- {"dt": "2025-03-25T10:29:13.066240", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-03-25T10:29:13.066353", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-03-25T10:29:13.066471", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-common-functions"}
- {"dt": "2025-03-25T10:29:13.066582", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-03-25T10:29:13.066700", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-install-packages"}
- {"dt": "2025-03-25T10:29:13.066812", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-03-25T10:29:13.066924", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-os-build"}
- {"dt": "2025-03-25T10:29:13.067043", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-03-25T10:29:13.067151", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-03-25T10:29:13.067265", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-target-ip"}
- {"dt": "2025-03-25T10:29:13.067375", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-target-mac"}
- {"dt": "2025-03-25T10:29:13.067484", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-target-storage"}
- {"dt": "2025-03-25T10:29:13.067595", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-case"}
- {"dt": "2025-03-25T10:29:13.067705", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-event"}
- {"dt": "2025-03-25T10:29:13.067813", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-03-25T10:29:13.067921", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-raise"}
- {"dt": "2025-03-25T10:29:13.068029", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-reference"}
- {"dt": "2025-03-25T10:29:13.068137", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-runner"}
- {"dt": "2025-03-25T10:29:13.068252", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-set"}
- {"dt": "2025-03-25T10:29:13.068359", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-test-shell"}
- {"dt": "2025-03-25T10:29:13.068469", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-03-25T10:29:13.068617", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-03-25T10:29:13.068725", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/environment"}
- {"dt": "2025-03-25T10:29:13.068806", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-03-25T10:29:13.068854", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-03-25T10:29:13.068895", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-03-25T10:29:13.068966", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069011", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-03-25T10:29:13.069061", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069117", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069156", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-03-25T10:29:13.069203", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069254", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069300", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-03-25T10:29:13.069356", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-03-25T10:29:13.069400", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-03-25T10:29:13.069467", "lvl": "info", "msg": "Fetching tests from https://github.com/TheodoreRTG/test-definitions.git"}
- {"dt": "2025-03-25T10:29:13.069524", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b kselftest-logs --depth=1 https://github.com/TheodoreRTG/test-definitions.git /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/0/tests/0_kselftest-core'"}
- {"dt": "2025-03-25T10:29:16.062923", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/0/tests/0_kselftest-core/automated/linux/kselftest/kselftest.yaml"}
- {"dt": "2025-03-25T10:29:16.063350", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-03-25T10:29:16.063438", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2025-03-25T10:29:16.063501", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "git-repo-action", "level": "1.1.3.1", "duration": "2.99", "result": "pass", "extra": {"commit": "450111580e70664ab07b21744eff0a1147707c44", "repository": "https://github.com/TheodoreRTG/test-definitions.git", "path": "automated/linux/kselftest/kselftest.yaml"}}}
- {"dt": "2025-03-25T10:29:16.063603", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-03-25T10:29:16.064191", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:16.064243", "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": "kselftest-core", "path": "automated/linux/kselftest/kselftest.yaml", "from": "git", "repository": "https://github.com/TheodoreRTG/test-definitions.git"}}}
- {"dt": "2025-03-25T10:29:16.064348", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-03-25T10:29:16.065146", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:16.065200", "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": "kselftest-core", "path": "automated/linux/kselftest/kselftest.yaml", "from": "git", "repository": "https://github.com/TheodoreRTG/test-definitions.git", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-03-25T10:29:16.065305", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-03-25T10:29:16.073537", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/0/tests/0_kselftest-core test_uuid 1_1.1.3.1"}
- {"dt": "2025-03-25T10:29:16.073597", "lvl": "debug", "msg": "TST_CMDFILES='core'"}
- {"dt": "2025-03-25T10:29:16.073639", "lvl": "debug", "msg": "KSELFTEST_PATH='/opt/kselftests/default-in-kernel'"}
- {"dt": "2025-03-25T10:29:16.073675", "lvl": "debug", "msg": "SKIPFILE='skipfile-lkft.yaml'"}
- {"dt": "2025-03-25T10:29:16.073710", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-03-25T10:29:16.073743", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-03-25T10:29:16.073788", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-03-25T10:29:16.073911", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:16.073964", "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": "kselftest-core", "path": "automated/linux/kselftest/kselftest.yaml", "from": "git", "repository": "https://github.com/TheodoreRTG/test-definitions.git", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/0/tests/0_kselftest-core/run.sh"}}}
- {"dt": "2025-03-25T10:29:16.074054", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-03-25T10:29:16.074094", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/lava-overlay-pc04c9np/lava-1/0 for stage 0"}
- {"dt": "2025-03-25T10:29:16.074167", "lvl": "debug", "msg": "- 0_kselftest-core"}
- {"dt": "2025-03-25T10:29:16.074243", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:03) [common]"}
- {"dt": "2025-03-25T10:29:16.074301", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-03-25T10:29:22.062249", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-03-25T10:29:22.062407", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2025-03-25T10:29:22.062471", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:14:51) [common]"}
- {"dt": "2025-03-25T10:29:22.062528", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-03-25T10:29:22.062577", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:22.062629", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:14:51) [common]"}
- {"dt": "2025-03-25T10:29:22.062677", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:22.062729", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:51) [common]"}
- {"dt": "2025-03-25T10:29:22.062787", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:22.062961", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/Image.gz"}
- {"dt": "2025-03-25T10:29:22.063013", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/kernel/Image"}
- {"dt": "2025-03-25T10:29:22.063053", "lvl": "debug", "msg": "total size: 14569912 (13\u202FMB)"}
- {"dt": "2025-03-25T10:29:22.063093", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2025-03-25T10:29:22.666266", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.038607", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.138614", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.239151", "lvl": "debug", "msg": "progress  17\u202F% (2\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.509129", "lvl": "debug", "msg": "progress  24\u202F% (3\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.609264", "lvl": "debug", "msg": "progress  39\u202F% (5\u202FMB)"}
- {"dt": "2025-03-25T10:29:23.803026", "lvl": "debug", "msg": "progress  48\u202F% (6\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.006065", "lvl": "debug", "msg": "progress  56\u202F% (7\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.107652", "lvl": "debug", "msg": "progress  75\u202F% (10\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.284114", "lvl": "debug", "msg": "progress  80\u202F% (11\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.386755", "lvl": "debug", "msg": "progress  91\u202F% (12\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.487268", "lvl": "debug", "msg": "progress  98\u202F% (13\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.523309", "lvl": "info", "msg": "13\u202FMB downloaded in 2.46\u202Fs (5.65\u202FMB/s)"}
- {"dt": "2025-03-25T10:29:24.523498", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:24.523577", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.4.1", "duration": "2.46", "result": "pass", "extra": {"label": "kernel", "size": ! "14569912", "sha256sum": "9f49bfcc793ea5bd9c25dd5d8f9bde6fc589925ccb5bc96b4191d327c0e34c8a"}}}
- {"dt": "2025-03-25T10:29:24.523720", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:24.523801", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:14:49) [common]"}
- {"dt": "2025-03-25T10:29:24.523879", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:24.524097", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4.xz"}
- {"dt": "2025-03-25T10:29:24.524170", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4"}
- {"dt": "2025-03-25T10:29:24.524221", "lvl": "debug", "msg": "total size: 111407012 (106\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.524261", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-03-25T10:29:24.624675", "lvl": "debug", "msg": "progress   2\u202F% (2\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.788411", "lvl": "debug", "msg": "progress   7\u202F% (7\u202FMB)"}
- {"dt": "2025-03-25T10:29:24.958935", "lvl": "debug", "msg": "progress  12\u202F% (12\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.118586", "lvl": "debug", "msg": "progress  17\u202F% (18\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.278246", "lvl": "debug", "msg": "progress  22\u202F% (23\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.451984", "lvl": "debug", "msg": "progress  27\u202F% (28\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.586584", "lvl": "debug", "msg": "progress  32\u202F% (34\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.743623", "lvl": "debug", "msg": "progress  37\u202F% (39\u202FMB)"}
- {"dt": "2025-03-25T10:29:25.911657", "lvl": "debug", "msg": "progress  42\u202F% (44\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.074224", "lvl": "debug", "msg": "progress  47\u202F% (49\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.239737", "lvl": "debug", "msg": "progress  52\u202F% (55\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.408539", "lvl": "debug", "msg": "progress  57\u202F% (60\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.593818", "lvl": "debug", "msg": "progress  62\u202F% (65\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.757864", "lvl": "debug", "msg": "progress  67\u202F% (71\u202FMB)"}
- {"dt": "2025-03-25T10:29:26.910089", "lvl": "debug", "msg": "progress  72\u202F% (76\u202FMB)"}
- {"dt": "2025-03-25T10:29:27.073196", "lvl": "debug", "msg": "progress  77\u202F% (81\u202FMB)"}
- {"dt": "2025-03-25T10:29:27.237939", "lvl": "debug", "msg": "progress  82\u202F% (87\u202FMB)"}
- {"dt": "2025-03-25T10:29:27.402530", "lvl": "debug", "msg": "progress  87\u202F% (92\u202FMB)"}
- {"dt": "2025-03-25T10:29:27.502691", "lvl": "debug", "msg": "progress  92\u202F% (97\u202FMB)"}
- {"dt": "2025-03-25T10:29:27.623033", "lvl": "debug", "msg": "progress  97\u202F% (103\u202FMB)"}
- {"dt": "2025-03-25T10:29:30.902840", "lvl": "info", "msg": "106\u202FMB downloaded in 6.38\u202Fs (16.66\u202FMB/s)"}
- {"dt": "2025-03-25T10:29:30.903083", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:06) [common]"}
- {"dt": "2025-03-25T10:29:30.903165", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "6.38", "result": "pass", "extra": {"label": "rootfs", "size": ! "111407012", "sha256sum": "71bcc7bc8dbd4ac7d02d52aed8886662999d6e6c790f9b9e5220beaf580ccfc1"}}}
- {"dt": "2025-03-25T10:29:30.903322", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:30.903400", "lvl": "debug", "msg": "start: 1.5.2.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-03-25T10:29:30.903592", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/kselftest.tar.xz"}
- {"dt": "2025-03-25T10:29:30.903644", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.kselftest/kselftest.tar"}
- {"dt": "2025-03-25T10:29:30.903684", "lvl": "debug", "msg": "total size: 8023924 (7\u202FMB)"}
- {"dt": "2025-03-25T10:29:30.903724", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-03-25T10:29:31.510814", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:31.792285", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:31.894796", "lvl": "debug", "msg": "progress  11\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:31.995473", "lvl": "debug", "msg": "progress  22\u202F% (1\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.097830", "lvl": "debug", "msg": "progress  36\u202F% (2\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.377662", "lvl": "debug", "msg": "progress  46\u202F% (3\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.478376", "lvl": "debug", "msg": "progress  75\u202F% (5\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.579488", "lvl": "debug", "msg": "progress  97\u202F% (7\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.646524", "lvl": "info", "msg": "7\u202FMB downloaded in 1.74\u202Fs (4.39\u202FMB/s)"}
- {"dt": "2025-03-25T10:29:32.646729", "lvl": "debug", "msg": "end: 1.5.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:32.646790", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.2.1", "duration": "1.74", "result": "pass", "extra": {"label": "rootfs.kselftest", "size": ! "8023924", "sha256sum": "fdb75542f4323788281ca32df629e3017778f9558f5d778749209d759868253b"}}}
- {"dt": "2025-03-25T10:29:32.646896", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:32.646964", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:32.647020", "lvl": "debug", "msg": "start: 1.5.3.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-03-25T10:29:32.647215", "lvl": "info", "msg": "downloading https://storage.tuxsuite.com/public/linaro/lkft/builds/2ulLQi70YHUMCevAw4vDWFVQfHN/modules.tar.xz"}
- {"dt": "2025-03-25T10:29:32.647275", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.modules/modules.tar"}
- {"dt": "2025-03-25T10:29:32.647316", "lvl": "debug", "msg": "total size: 11798000 (11\u202FMB)"}
- {"dt": "2025-03-25T10:29:32.647355", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-03-25T10:29:32.912562", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.077603", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.180273", "lvl": "debug", "msg": "progress  14\u202F% (1\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.281175", "lvl": "debug", "msg": "progress  26\u202F% (3\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.382111", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.483524", "lvl": "debug", "msg": "progress  44\u202F% (4\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.584252", "lvl": "debug", "msg": "progress  51\u202F% (5\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.687153", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.789043", "lvl": "debug", "msg": "progress  68\u202F% (7\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.891348", "lvl": "debug", "msg": "progress  77\u202F% (8\u202FMB)"}
- {"dt": "2025-03-25T10:29:33.994188", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2025-03-25T10:29:34.094365", "lvl": "debug", "msg": "progress  94\u202F% (10\u202FMB)"}
- {"dt": "2025-03-25T10:29:34.194289", "lvl": "info", "msg": "11\u202FMB downloaded in 1.55\u202Fs (7.27\u202FMB/s)"}
- {"dt": "2025-03-25T10:29:34.194534", "lvl": "debug", "msg": "end: 1.5.3.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:34.194620", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.3.1", "duration": "1.55", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "11798000", "sha256sum": "5b6c182841b9377518b1324e207cfbc57ed8d4eefa14147b0bf6dd6d83c68de5"}}}
- {"dt": "2025-03-25T10:29:34.194770", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:02) [common]"}
- {"dt": "2025-03-25T10:29:34.194854", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:34.194924", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4'"}
- {"dt": "2025-03-25T10:29:35.787158", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-03-25T10:29:35.787999", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/compress-overlay-3i8bmi8l/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-03-25T10:29:36.269531", "lvl": "debug", "msg": "- rootfs.kselftest: '/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.kselftest/kselftest.tar' to '/opt/kselftests/default-in-kernel/'"}
- {"dt": "2025-03-25T10:29:36.560629", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2025-03-25T10:29:36.956188", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2025-03-25T10:29:36.956581", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:12) [common]"}
- {"dt": "2025-03-25T10:29:36.956692", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:24) [common]"}
- {"dt": "2025-03-25T10:29:36.956811", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:30:00) [common]"}
- {"dt": "2025-03-25T10:29:36.956891", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:30:00) [common]"}
- {"dt": "2025-03-25T10:29:36.956950", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:30:00) [common]"}
- {"dt": "2025-03-25T10:29:36.957190", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu max,pauth-impdef=on -machine virt,virtualization=on,gic-version=3,mte=on -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-03-25T10:29:36.960478", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-03-25T10:29:36.960753", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:36.960818", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "1:9.2.1+ds-1", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2025-03-25T10:29:36.960986", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:36.961084", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:30:00) [common]"}
- {"dt": "2025-03-25T10:29:36.961183", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-03-25T10:29:36.961259", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2025-03-25T10:29:37.070774", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2025-03-25T10:29:37.070958", "lvl": "target", "msg": "[    0.000000] Linux version 6.14.0-rc7-next-20250324 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 13.3.0-12) 13.3.0, GNU ld (GNU Binutils for Debian) 2.44) #1 SMP PREEMPT @1742818529"}
- {"dt": "2025-03-25T10:29:37.071054", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2025-03-25T10:29:37.071132", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2025-03-25T10:29:37.071207", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2025-03-25T10:29:37.071283", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2025-03-25T10:29:37.071416", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2025-03-25T10:29:37.071542", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2025-03-25T10:29:37.071842", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:30:00) [common]"}
- {"dt": "2025-03-25T10:29:37.071933", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-03-25T10:29:37.072001", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2025-03-25T10:29:37.072058", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-03-25T10:29:37.072098", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-03-25T10:29:37.072137", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-03-25T10:29:37.072171", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2025-03-25T10:29:37.072246", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:30:00)"}
- {"dt": "2025-03-25T10:29:37.072289", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-03-25T10:29:37.072653", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT"}
- {"dt": "2025-03-25T10:29:37.075580", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-03-25T10:29:37.076298", "lvl": "target", "msg": "[    0.000000] NODE_DATA(0) allocated [mem 0x13f7f9000-0x13f7fb77f]"}
- {"dt": "2025-03-25T10:29:37.078518", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2025-03-25T10:29:37.078900", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2025-03-25T10:29:37.079000", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2025-03-25T10:29:37.079181", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2025-03-25T10:29:37.079294", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2025-03-25T10:29:37.079408", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2025-03-25T10:29:37.079600", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-03-25T10:29:37.079970", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-03-25T10:29:37.105132", "lvl": "target", "msg": "[    0.000000] cma: Reserved 32 MiB at 0x0000000000000000"}
- {"dt": "2025-03-25T10:29:37.106187", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2025-03-25T10:29:37.106318", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-03-25T10:29:37.106502", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-03-25T10:29:37.106784", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2025-03-25T10:29:37.107054", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2025-03-25T10:29:37.109196", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 25 pages/cpu s62808 r8192 d31400 u102400"}
- {"dt": "2025-03-25T10:29:37.109711", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s62808 r8192 d31400 u102400 alloc=25*4096"}
- {"dt": "2025-03-25T10:29:37.109885", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-03-25T10:29:37.111060", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2025-03-25T10:29:37.112476", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2025-03-25T10:29:37.112978", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2025-03-25T10:29:37.113066", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2025-03-25T10:29:37.113435", "lvl": "target", "msg": "[    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)"}
- {"dt": "2025-03-25T10:29:37.113546", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2025-03-25T10:29:37.113769", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2025-03-25T10:29:37.113963", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2025-03-25T10:29:37.114274", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2025-03-25T10:29:37.117401", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2025-03-25T10:29:37.119795", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon"}
- {"dt": "2025-03-25T10:29:37.121764", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-03-25T10:29:37.123626", "lvl": "target", "msg": "<6>[    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes"}
- {"dt": "2025-03-25T10:29:37.125750", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.127072", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.127597", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2025-03-25T10:29:37.176764", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)"}
- {"dt": "2025-03-25T10:29:37.178217", "lvl": "target", "msg": "<6>[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2025-03-25T10:29:37.178288", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2025-03-25T10:29:37.178367", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2025-03-25T10:29:37.178955", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-03-25T10:29:37.198310", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-03-25T10:29:37.205480", "lvl": "target", "msg": "<6>[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2025-03-25T10:29:37.205583", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2025-03-25T10:29:37.205866", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2."}
- {"dt": "2025-03-25T10:29:37.206180", "lvl": "target", "msg": "<6>[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2025-03-25T10:29:37.206502", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2025-03-25T10:29:37.206829", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2025-03-25T10:29:37.206881", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-03-25T10:29:37.208130", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2025-03-25T10:29:37.208461", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2025-03-25T10:29:37.208874", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2025-03-25T10:29:37.214393", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2025-03-25T10:29:37.214719", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2025-03-25T10:29:37.214829", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-03-25T10:29:37.216217", "lvl": "target", "msg": "<6>[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2025-03-25T10:29:37.216531", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-03-25T10:29:37.216617", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2025-03-25T10:29:37.217209", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2025-03-25T10:29:37.221767", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2025-03-25T10:29:37.222593", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100050000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-03-25T10:29:37.222932", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100060000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-03-25T10:29:37.223597", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000100070000"}
- {"dt": "2025-03-25T10:29:37.224305", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100080000"}
- {"dt": "2025-03-25T10:29:37.226414", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-03-25T10:29:37.232579", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (phys)."}
- {"dt": "2025-03-25T10:29:37.232996", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns"}
- {"dt": "2025-03-25T10:29:37.233528", "lvl": "target", "msg": "<6>[    0.000063] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns"}
- {"dt": "2025-03-25T10:29:37.248335", "lvl": "target", "msg": "<6>[    0.013087] Console: colour dummy device 80x25"}
- {"dt": "2025-03-25T10:29:37.252330", "lvl": "target", "msg": "<6>[    0.018090] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)"}
- {"dt": "2025-03-25T10:29:37.252578", "lvl": "target", "msg": "<6>[    0.019217] pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-03-25T10:29:37.255008", "lvl": "target", "msg": "<6>[    0.021480] LSM: initializing lsm=capability"}
- {"dt": "2025-03-25T10:29:37.258373", "lvl": "target", "msg": "<6>[    0.024842] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.258786", "lvl": "target", "msg": "<6>[    0.025268] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.285857", "lvl": "target", "msg": "<4>[    0.052390] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-03-25T10:29:37.309740", "lvl": "target", "msg": "<6>[    0.076252] rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-03-25T10:29:37.309856", "lvl": "target", "msg": "<6>[    0.076453] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-03-25T10:29:37.312134", "lvl": "target", "msg": "<6>[    0.078653] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2025-03-25T10:29:37.315549", "lvl": "target", "msg": "<6>[    0.082092] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2025-03-25T10:29:37.318323", "lvl": "target", "msg": "<6>[    0.084863] EFI services will not be available."}
- {"dt": "2025-03-25T10:29:37.319971", "lvl": "target", "msg": "<6>[    0.086555] smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-03-25T10:29:37.334798", "lvl": "target", "msg": "<6>[    0.093666] Detected PIPT I-cache on CPU1"}
- {"dt": "2025-03-25T10:29:37.334997", "lvl": "target", "msg": "<6>[    0.095006] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2025-03-25T10:29:37.335270", "lvl": "target", "msg": "<6>[    0.095763] GICv3: CPU1: using allocated LPI pending table @0x0000000100090000"}
- {"dt": "2025-03-25T10:29:37.335524", "lvl": "target", "msg": "<6>[    0.097908] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2025-03-25T10:29:37.335642", "lvl": "target", "msg": "<6>[    0.101312] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-03-25T10:29:37.335812", "lvl": "target", "msg": "<6>[    0.102483] SMP: Total of 2 processors activated."}
- {"dt": "2025-03-25T10:29:37.336136", "lvl": "target", "msg": "<6>[    0.102661] CPU: All CPU(s) started at EL2"}
- {"dt": "2025-03-25T10:29:37.336397", "lvl": "target", "msg": "<6>[    0.102997] CPU features: detected: Branch Target Identification"}
- {"dt": "2025-03-25T10:29:37.336587", "lvl": "target", "msg": "<6>[    0.103242] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2025-03-25T10:29:37.336767", "lvl": "target", "msg": "<6>[    0.103423] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2025-03-25T10:29:37.336971", "lvl": "target", "msg": "<6>[    0.103597] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2025-03-25T10:29:37.337268", "lvl": "target", "msg": "<6>[    0.103810] CPU features: detected: Instruction cache invalidation not required for I/D coherence"}
- {"dt": "2025-03-25T10:29:37.337547", "lvl": "target", "msg": "<6>[    0.104105] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2025-03-25T10:29:37.337802", "lvl": "target", "msg": "<6>[    0.104405] CPU features: detected: Common not Private translations"}
- {"dt": "2025-03-25T10:29:37.337957", "lvl": "target", "msg": "<6>[    0.104612] CPU features: detected: CRC32 instructions"}
- {"dt": "2025-03-25T10:29:37.338159", "lvl": "target", "msg": "<6>[    0.104783] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2025-03-25T10:29:37.338288", "lvl": "target", "msg": "<6>[    0.104988] CPU features: detected: E0PD"}
- {"dt": "2025-03-25T10:29:37.338581", "lvl": "target", "msg": "<6>[    0.105119] CPU features: detected: Enhanced Counter Virtualization"}
- {"dt": "2025-03-25T10:29:37.338797", "lvl": "target", "msg": "<6>[    0.105376] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)"}
- {"dt": "2025-03-25T10:29:37.338987", "lvl": "target", "msg": "<6>[    0.105622] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2025-03-25T10:29:37.339162", "lvl": "target", "msg": "<6>[    0.105814] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2025-03-25T10:29:37.339318", "lvl": "target", "msg": "<6>[    0.105989] CPU features: detected: Fine Grained Traps"}
- {"dt": "2025-03-25T10:29:37.339618", "lvl": "target", "msg": "<6>[    0.106203] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2025-03-25T10:29:37.339874", "lvl": "target", "msg": "<6>[    0.106499] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2025-03-25T10:29:37.340227", "lvl": "target", "msg": "<6>[    0.106729] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2025-03-25T10:29:37.340396", "lvl": "target", "msg": "<6>[    0.107028] CPU features: detected: LSE atomic instructions"}
- {"dt": "2025-03-25T10:29:37.340619", "lvl": "target", "msg": "<6>[    0.107224] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2025-03-25T10:29:37.340793", "lvl": "target", "msg": "<6>[    0.107447] CPU features: detected: Privileged Access Never"}
- {"dt": "2025-03-25T10:29:37.341052", "lvl": "target", "msg": "<6>[    0.107652] CPU features: detected: PMUv3"}
- {"dt": "2025-03-25T10:29:37.341143", "lvl": "target", "msg": "<6>[    0.107795] CPU features: detected: RAS Extension Support"}
- {"dt": "2025-03-25T10:29:37.341316", "lvl": "target", "msg": "<6>[    0.107971] CPU features: detected: Random Number Generator"}
- {"dt": "2025-03-25T10:29:37.341513", "lvl": "target", "msg": "<6>[    0.108145] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2025-03-25T10:29:37.341705", "lvl": "target", "msg": "<6>[    0.108336] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2025-03-25T10:29:37.341932", "lvl": "target", "msg": "<6>[    0.108527] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2025-03-25T10:29:37.342139", "lvl": "target", "msg": "<6>[    0.108760] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2025-03-25T10:29:37.342277", "lvl": "target", "msg": "<6>[    0.108968] CPU features: detected: WFx with timeout"}
- {"dt": "2025-03-25T10:29:37.342767", "lvl": "target", "msg": "<6>[    0.109180] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2025-03-25T10:29:37.342828", "lvl": "target", "msg": "<6>[    0.109467] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2025-03-25T10:29:37.352211", "lvl": "target", "msg": "<6>[    0.116627] alternatives: applying system-wide alternatives"}
- {"dt": "2025-03-25T10:29:37.362973", "lvl": "target", "msg": "<6>[    0.128921] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2025-03-25T10:29:37.363319", "lvl": "target", "msg": "<6>[    0.129874] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2025-03-25T10:29:37.363453", "lvl": "target", "msg": "<6>[    0.130078] SVE: default vector length 64 bytes per vector"}
- {"dt": "2025-03-25T10:29:37.375159", "lvl": "target", "msg": "<6>[    0.141569] Memory: 3968692K/4194304K available (18240K kernel code, 5264K rwdata, 12488K rodata, 3264K init, 748K bss, 188664K reserved, 32768K cma-reserved)"}
- {"dt": "2025-03-25T10:29:37.397557", "lvl": "target", "msg": "<6>[    0.164050] devtmpfs: initialized"}
- {"dt": "2025-03-25T10:29:37.423464", "lvl": "target", "msg": "<6>[    0.189967] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2025-03-25T10:29:37.423976", "lvl": "target", "msg": "<6>[    0.190547] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.424243", "lvl": "target", "msg": "<6>[    0.190838] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.426080", "lvl": "target", "msg": "<6>[    0.192623] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2025-03-25T10:29:37.426208", "lvl": "target", "msg": "<6>[    0.192852] 0 pages in range for non-PLT usage"}
- {"dt": "2025-03-25T10:29:37.429118", "lvl": "target", "msg": "<6>[    0.192897] 514224 pages in range for PLT usage"}
- {"dt": "2025-03-25T10:29:37.429184", "lvl": "target", "msg": "<6>[    0.195666] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2025-03-25T10:29:37.438954", "lvl": "target", "msg": "<6>[    0.205496] DMI not present or invalid."}
- {"dt": "2025-03-25T10:29:37.452348", "lvl": "target", "msg": "<6>[    0.218886] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-03-25T10:29:37.460909", "lvl": "target", "msg": "<6>[    0.227374] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2025-03-25T10:29:37.461582", "lvl": "target", "msg": "<6>[    0.228115] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2025-03-25T10:29:37.462220", "lvl": "target", "msg": "<6>[    0.228799] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2025-03-25T10:29:37.462552", "lvl": "target", "msg": "<6>[    0.229202] audit: initializing netlink subsys (disabled)"}
- {"dt": "2025-03-25T10:29:37.464155", "lvl": "target", "msg": "<5>[    0.230734] audit: type=2000 audit(0.208:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2025-03-25T10:29:37.468603", "lvl": "target", "msg": "<6>[    0.235097] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-03-25T10:29:37.469183", "lvl": "target", "msg": "<6>[    0.235139] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2025-03-25T10:29:37.469271", "lvl": "target", "msg": "<6>[    0.235723] cpuidle: using governor menu"}
- {"dt": "2025-03-25T10:29:37.470647", "lvl": "target", "msg": "<6>[    0.237220] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2025-03-25T10:29:37.471419", "lvl": "target", "msg": "<6>[    0.237969] ASID allocator initialised with 65536 entries"}
- {"dt": "2025-03-25T10:29:37.478928", "lvl": "target", "msg": "<6>[    0.245472] Serial: AMBA PL011 UART driver"}
- {"dt": "2025-03-25T10:29:37.536757", "lvl": "target", "msg": "<6>[    0.303015] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 15, base_baud = 0) is a PL011 rev1"}
- {"dt": "2025-03-25T10:29:37.538810", "lvl": "target", "msg": "<6>[    0.304958] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-03-25T10:29:37.539110", "lvl": "target", "msg": "<6>[    0.304958] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-03-25T10:29:37.539314", "lvl": "target", "msg": "<6>[    0.305970] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-03-25T10:29:37.539523", "lvl": "target", "msg": "<6>[    0.305970] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-03-25T10:29:37.556017", "lvl": "target", "msg": "<6>[    0.322228] HugeTLB: allocation took 0ms with hugepage_allocation_threads=1"}
- {"dt": "2025-03-25T10:29:37.556330", "lvl": "target", "msg": "<6>[    0.322501] HugeTLB: allocation took 0ms with hugepage_allocation_threads=1"}
- {"dt": "2025-03-25T10:29:37.556577", "lvl": "target", "msg": "<6>[    0.322866] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2025-03-25T10:29:37.556806", "lvl": "target", "msg": "<6>[    0.322885] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2025-03-25T10:29:37.557211", "lvl": "target", "msg": "<6>[    0.322921] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2025-03-25T10:29:37.557311", "lvl": "target", "msg": "<6>[    0.322934] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2025-03-25T10:29:37.557663", "lvl": "target", "msg": "<6>[    0.322957] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2025-03-25T10:29:37.557888", "lvl": "target", "msg": "<6>[    0.322970] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2025-03-25T10:29:37.558249", "lvl": "target", "msg": "<6>[    0.322996] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2025-03-25T10:29:37.558353", "lvl": "target", "msg": "<6>[    0.323008] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2025-03-25T10:29:37.566101", "lvl": "target", "msg": "<6>[    0.332563] ACPI: Interpreter disabled."}
- {"dt": "2025-03-25T10:29:37.582271", "lvl": "target", "msg": "<6>[    0.348565] iommu: Default domain type: Translated"}
- {"dt": "2025-03-25T10:29:37.582464", "lvl": "target", "msg": "<6>[    0.348684] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2025-03-25T10:29:37.583994", "lvl": "target", "msg": "<5>[    0.350490] SCSI subsystem initialized"}
- {"dt": "2025-03-25T10:29:37.584782", "lvl": "target", "msg": "<7>[    0.351288] libata version 3.00 loaded."}
- {"dt": "2025-03-25T10:29:37.586205", "lvl": "target", "msg": "<6>[    0.352720] usbcore: registered new interface driver usbfs"}
- {"dt": "2025-03-25T10:29:37.586343", "lvl": "target", "msg": "<6>[    0.352922] usbcore: registered new interface driver hub"}
- {"dt": "2025-03-25T10:29:37.586546", "lvl": "target", "msg": "<6>[    0.353062] usbcore: registered new device driver usb"}
- {"dt": "2025-03-25T10:29:37.588926", "lvl": "target", "msg": "<6>[    0.355414] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-03-25T10:29:37.589489", "lvl": "target", "msg": "<6>[    0.355456] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-03-25T10:29:37.589896", "lvl": "target", "msg": "<6>[    0.355532] PTP clock support registered"}
- {"dt": "2025-03-25T10:29:37.589997", "lvl": "target", "msg": "<6>[    0.356565] EDAC MC: Ver: 3.0.0"}
- {"dt": "2025-03-25T10:29:37.591295", "lvl": "target", "msg": "<6>[    0.357807] scmi_core: SCMI protocol bus registered"}
- {"dt": "2025-03-25T10:29:37.594818", "lvl": "target", "msg": "<6>[    0.361260] FPGA manager framework"}
- {"dt": "2025-03-25T10:29:37.595408", "lvl": "target", "msg": "<6>[    0.361903] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2025-03-25T10:29:37.604350", "lvl": "target", "msg": "<6>[    0.370832] vgaarb: loaded"}
- {"dt": "2025-03-25T10:29:37.606232", "lvl": "target", "msg": "<6>[    0.372698] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-03-25T10:29:37.608912", "lvl": "target", "msg": "<5>[    0.375271] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2025-03-25T10:29:37.609166", "lvl": "target", "msg": "<6>[    0.375392] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2025-03-25T10:29:37.610967", "lvl": "target", "msg": "<6>[    0.377422] pnp: PnP ACPI: disabled"}
- {"dt": "2025-03-25T10:29:37.637624", "lvl": "target", "msg": "<6>[    0.404040] NET: Registered PF_INET protocol family"}
- {"dt": "2025-03-25T10:29:37.638880", "lvl": "target", "msg": "<6>[    0.405063] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.643446", "lvl": "target", "msg": "<6>[    0.409621] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.644034", "lvl": "target", "msg": "<6>[    0.409725] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.644434", "lvl": "target", "msg": "<6>[    0.409837] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.644754", "lvl": "target", "msg": "<6>[    0.410230] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.645024", "lvl": "target", "msg": "<6>[    0.410774] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2025-03-25T10:29:37.645452", "lvl": "target", "msg": "<6>[    0.411782] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.645828", "lvl": "target", "msg": "<6>[    0.412053] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-03-25T10:29:37.646832", "lvl": "target", "msg": "<6>[    0.413270] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-03-25T10:29:37.649054", "lvl": "target", "msg": "<6>[    0.415485] RPC: Registered named UNIX socket transport module."}
- {"dt": "2025-03-25T10:29:37.649208", "lvl": "target", "msg": "<6>[    0.415555] RPC: Registered udp transport module."}
- {"dt": "2025-03-25T10:29:37.649637", "lvl": "target", "msg": "<6>[    0.415592] RPC: Registered tcp transport module."}
- {"dt": "2025-03-25T10:29:37.649713", "lvl": "target", "msg": "<6>[    0.415607] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2025-03-25T10:29:37.649916", "lvl": "target", "msg": "<6>[    0.415635] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2025-03-25T10:29:37.650254", "lvl": "target", "msg": "<6>[    0.415742] PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-03-25T10:29:37.661368", "lvl": "target", "msg": "<6>[    0.427789] kvm [1]: nv: 566 coarse grained trap handlers"}
- {"dt": "2025-03-25T10:29:37.662386", "lvl": "target", "msg": "<6>[    0.428907] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2025-03-25T10:29:37.663441", "lvl": "target", "msg": "<6>[    0.429959] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2025-03-25T10:29:37.664230", "lvl": "target", "msg": "<6>[    0.430545] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2025-03-25T10:29:37.664396", "lvl": "target", "msg": "<6>[    0.430666] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2025-03-25T10:29:37.664719", "lvl": "target", "msg": "<6>[    0.431021] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2025-03-25T10:29:37.665173", "lvl": "target", "msg": "<6>[    0.431681] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2025-03-25T10:29:37.666012", "lvl": "target", "msg": "<6>[    0.432251] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2025-03-25T10:29:37.670586", "lvl": "target", "msg": "<5>[    0.436927] Initialise system trusted keyrings"}
- {"dt": "2025-03-25T10:29:37.674090", "lvl": "target", "msg": "<6>[    0.438230] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2025-03-25T10:29:37.674403", "lvl": "target", "msg": "<6>[    0.439957] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2025-03-25T10:29:37.675786", "lvl": "target", "msg": "<5>[    0.442304] NFS: Registering the id_resolver key type"}
- {"dt": "2025-03-25T10:29:37.675981", "lvl": "target", "msg": "<5>[    0.442564] Key type id_resolver registered"}
- {"dt": "2025-03-25T10:29:37.676135", "lvl": "target", "msg": "<5>[    0.442609] Key type id_legacy registered"}
- {"dt": "2025-03-25T10:29:37.676584", "lvl": "target", "msg": "<6>[    0.442894] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2025-03-25T10:29:37.676671", "lvl": "target", "msg": "<6>[    0.442970] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2025-03-25T10:29:37.677027", "lvl": "target", "msg": "<6>[    0.443562] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2025-03-25T10:29:37.742987", "lvl": "target", "msg": "<5>[    0.509360] Key type asymmetric registered"}
- {"dt": "2025-03-25T10:29:37.743401", "lvl": "target", "msg": "<5>[    0.509431] Asymmetric key parser 'x509' registered"}
- {"dt": "2025-03-25T10:29:37.743485", "lvl": "target", "msg": "<6>[    0.509649] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2025-03-25T10:29:37.743643", "lvl": "target", "msg": "<6>[    0.509763] io scheduler mq-deadline registered"}
- {"dt": "2025-03-25T10:29:37.743820", "lvl": "target", "msg": "<6>[    0.509831] io scheduler kyber registered"}
- {"dt": "2025-03-25T10:29:37.744107", "lvl": "target", "msg": "<6>[    0.510273] io scheduler bfq registered"}
- {"dt": "2025-03-25T10:29:37.771753", "lvl": "target", "msg": "<6>[    0.538142] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2025-03-25T10:29:37.776093", "lvl": "target", "msg": "<6>[    0.542536] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2025-03-25T10:29:37.779332", "lvl": "target", "msg": "<6>[    0.545765] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2025-03-25T10:29:37.780099", "lvl": "target", "msg": "<6>[    0.546264] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2025-03-25T10:29:37.780431", "lvl": "target", "msg": "<6>[    0.546692] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2025-03-25T10:29:37.780736", "lvl": "target", "msg": "<6>[    0.546762] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2025-03-25T10:29:37.781083", "lvl": "target", "msg": "<4>[    0.547198] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-03-25T10:29:37.781532", "lvl": "target", "msg": "<6>[    0.547611] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2025-03-25T10:29:37.782447", "lvl": "target", "msg": "<6>[    0.548932] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2025-03-25T10:29:37.782740", "lvl": "target", "msg": "<6>[    0.549165] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-03-25T10:29:37.783052", "lvl": "target", "msg": "<6>[    0.549241] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-03-25T10:29:37.783332", "lvl": "target", "msg": "<6>[    0.549285] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-03-25T10:29:37.783702", "lvl": "target", "msg": "<6>[    0.549300] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-03-25T10:29:37.784223", "lvl": "target", "msg": "<6>[    0.550468] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2025-03-25T10:29:37.787853", "lvl": "target", "msg": "<6>[    0.553946] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2025-03-25T10:29:37.788246", "lvl": "target", "msg": "<6>[    0.554224] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2025-03-25T10:29:37.788688", "lvl": "target", "msg": "<6>[    0.554392] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-03-25T10:29:37.788863", "lvl": "target", "msg": "<6>[    0.554471] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-03-25T10:29:37.791294", "lvl": "target", "msg": "<6>[    0.557545] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2025-03-25T10:29:37.791513", "lvl": "target", "msg": "<6>[    0.557861] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2025-03-25T10:29:37.791760", "lvl": "target", "msg": "<6>[    0.557906] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2025-03-25T10:29:37.791971", "lvl": "target", "msg": "<6>[    0.558171] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2025-03-25T10:29:37.792220", "lvl": "target", "msg": "<6>[    0.558213] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-03-25T10:29:37.792580", "lvl": "target", "msg": "<6>[    0.558231] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-03-25T10:29:37.856162", "lvl": "target", "msg": "<6>[    0.621771] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)"}
- {"dt": "2025-03-25T10:29:37.875766", "lvl": "target", "msg": "<6>[    0.642196] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2025-03-25T10:29:37.885012", "lvl": "target", "msg": "<6>[    0.651426] msm_serial: driver initialized"}
- {"dt": "2025-03-25T10:29:37.885822", "lvl": "target", "msg": "<6>[    0.652362] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2025-03-25T10:29:37.886432", "lvl": "target", "msg": "<6>[    0.652951] STM32 USART driver initialized"}
- {"dt": "2025-03-25T10:29:37.911402", "lvl": "target", "msg": "<6>[    0.677851] loop: module loaded"}
- {"dt": "2025-03-25T10:29:37.912159", "lvl": "target", "msg": "<6>[    0.678612] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-03-25T10:29:37.920652", "lvl": "target", "msg": "<5>[    0.686742] virtio_blk virtio0: [vda] 5345376 512-byte logical blocks (2.74 GB/2.55 GiB)"}
- {"dt": "2025-03-25T10:29:37.932218", "lvl": "target", "msg": "<6>[    0.698636] megasas: 07.727.03.00-rc1"}
- {"dt": "2025-03-25T10:29:37.937619", "lvl": "target", "msg": "<5>[    0.703296] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2025-03-25T10:29:37.938946", "lvl": "target", "msg": "<6>[    0.704912] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-03-25T10:29:37.939269", "lvl": "target", "msg": "<6>[    0.705278] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-03-25T10:29:37.939424", "lvl": "target", "msg": "<6>[    0.706039] Using buffer write method"}
- {"dt": "2025-03-25T10:29:37.940188", "lvl": "target", "msg": "<7>[    0.706316] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-03-25T10:29:37.941162", "lvl": "target", "msg": "<5>[    0.706528] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2025-03-25T10:29:37.941510", "lvl": "target", "msg": "<6>[    0.707131] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-03-25T10:29:37.941819", "lvl": "target", "msg": "<6>[    0.707184] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-03-25T10:29:37.941953", "lvl": "target", "msg": "<6>[    0.707829] Using buffer write method"}
- {"dt": "2025-03-25T10:29:37.942170", "lvl": "target", "msg": "<7>[    0.707853] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-03-25T10:29:37.942521", "lvl": "target", "msg": "<5>[    0.707913] Concatenating MTD devices:"}
- {"dt": "2025-03-25T10:29:37.942567", "lvl": "target", "msg": "<5>[    0.707942] (0): \"0.flash\""}
- {"dt": "2025-03-25T10:29:37.942629", "lvl": "target", "msg": "<5>[    0.707971] (1): \"0.flash\""}
- {"dt": "2025-03-25T10:29:37.942705", "lvl": "target", "msg": "<5>[    0.707991] into device \"0.flash\""}
- {"dt": "2025-03-25T10:29:37.966150", "lvl": "target", "msg": "<6>[    0.732280] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2025-03-25T10:29:37.969197", "lvl": "target", "msg": "<6>[    0.735654] thunder_xcv, ver 1.0"}
- {"dt": "2025-03-25T10:29:37.969534", "lvl": "target", "msg": "<6>[    0.736022] thunder_bgx, ver 1.0"}
- {"dt": "2025-03-25T10:29:37.969624", "lvl": "target", "msg": "<6>[    0.736190] nicpf, ver 1.0"}
- {"dt": "2025-03-25T10:29:37.973003", "lvl": "target", "msg": "<6>[    0.739363] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2025-03-25T10:29:37.973500", "lvl": "target", "msg": "<6>[    0.739405] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2025-03-25T10:29:37.973603", "lvl": "target", "msg": "<6>[    0.739725] hclge is initializing"}
- {"dt": "2025-03-25T10:29:37.974019", "lvl": "target", "msg": "<6>[    0.740172] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-03-25T10:29:37.974132", "lvl": "target", "msg": "<6>[    0.740193] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2025-03-25T10:29:37.974319", "lvl": "target", "msg": "<6>[    0.740306] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-03-25T10:29:37.974552", "lvl": "target", "msg": "<6>[    0.740319] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2025-03-25T10:29:37.974757", "lvl": "target", "msg": "<6>[    0.740458] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2025-03-25T10:29:37.974962", "lvl": "target", "msg": "<6>[    0.740491] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2025-03-25T10:29:37.975291", "lvl": "target", "msg": "<6>[    0.740733] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2025-03-25T10:29:37.975419", "lvl": "target", "msg": "<6>[    0.740752] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2025-03-25T10:29:37.975557", "lvl": "target", "msg": "<6>[    0.741785] sky2: driver version 1.30"}
- {"dt": "2025-03-25T10:29:37.980017", "lvl": "target", "msg": "<6>[    0.746422] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2025-03-25T10:29:37.988350", "lvl": "target", "msg": "<6>[    0.754755] usbcore: registered new interface driver usb-storage"}
- {"dt": "2025-03-25T10:29:37.997434", "lvl": "target", "msg": "<6>[    0.763900] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2025-03-25T10:29:37.998355", "lvl": "target", "msg": "<6>[    0.764528] rtc-pl031 9010000.pl031: setting system clock to 2025-03-25T10:29:37 UTC (1742898577)"}
- {"dt": "2025-03-25T10:29:38.000138", "lvl": "target", "msg": "<6>[    0.766610] i2c_dev: i2c /dev entries driver"}
- {"dt": "2025-03-25T10:29:38.018284", "lvl": "target", "msg": "<6>[    0.784606] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2025-03-25T10:29:38.018504", "lvl": "target", "msg": "<6>[    0.784638] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2025-03-25T10:29:38.020355", "lvl": "target", "msg": "<6>[    0.786783] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2025-03-25T10:29:38.022761", "lvl": "target", "msg": "<6>[    0.789152] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2025-03-25T10:29:38.031926", "lvl": "target", "msg": "<6>[    0.798030] usbcore: registered new interface driver usbhid"}
- {"dt": "2025-03-25T10:29:38.031996", "lvl": "target", "msg": "<6>[    0.798083] usbhid: USB HID core driver"}
- {"dt": "2025-03-25T10:29:38.041846", "lvl": "target", "msg": "<6>[    0.807901] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2025-03-25T10:29:38.053489", "lvl": "target", "msg": "<6>[    0.819930] NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-03-25T10:29:38.058806", "lvl": "target", "msg": "<6>[    0.825283] Segment Routing with IPv6"}
- {"dt": "2025-03-25T10:29:38.058905", "lvl": "target", "msg": "<6>[    0.825509] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-03-25T10:29:38.059485", "lvl": "target", "msg": "<6>[    0.825978] NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-03-25T10:29:38.060466", "lvl": "target", "msg": "<6>[    0.826975] 9pnet: Installing 9P2000 support"}
- {"dt": "2025-03-25T10:29:38.060642", "lvl": "target", "msg": "<5>[    0.827253] Key type dns_resolver registered"}
- {"dt": "2025-03-25T10:29:38.089086", "lvl": "target", "msg": "<6>[    0.855509] registered taskstats version 1"}
- {"dt": "2025-03-25T10:29:38.090313", "lvl": "target", "msg": "<5>[    0.856798] Loading compiled-in X.509 certificates"}
- {"dt": "2025-03-25T10:29:38.114785", "lvl": "target", "msg": "<6>[    0.881212] Demotion targets for Node 0: null"}
- {"dt": "2025-03-25T10:29:38.122221", "lvl": "target", "msg": "<6>[    0.888304] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2025-03-25T10:29:38.127769", "lvl": "target", "msg": "<6>[    0.894224] clk: Disabling unused clocks"}
- {"dt": "2025-03-25T10:29:38.127917", "lvl": "target", "msg": "<6>[    0.894488] PM: genpd: Disabling unused power domains"}
- {"dt": "2025-03-25T10:29:38.128036", "lvl": "target", "msg": "<6>[    0.894732] ALSA device list:"}
- {"dt": "2025-03-25T10:29:38.128170", "lvl": "target", "msg": "<6>[    0.894808]   No soundcards found."}
- {"dt": "2025-03-25T10:29:38.154902", "lvl": "target", "msg": "<6>[    0.921099] EXT4-fs (vda): mounted filesystem db78a389-4e12-4f05-bb8b-e7b25b5f99d9 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2025-03-25T10:29:38.155134", "lvl": "target", "msg": "<6>[    0.921517] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2025-03-25T10:29:38.159534", "lvl": "target", "msg": "<6>[    0.926006] devtmpfs: mounted"}
- {"dt": "2025-03-25T10:29:38.456661", "lvl": "target", "msg": "<6>[    1.222849] Freeing unused kernel memory: 3264K"}
- {"dt": "2025-03-25T10:29:38.457405", "lvl": "target", "msg": "<6>[    1.223768] Run /sbin/init as init process"}
- {"dt": "2025-03-25T10:29:38.457509", "lvl": "target", "msg": "<7>[    1.223851]   with arguments:"}
- {"dt": "2025-03-25T10:29:38.457614", "lvl": "target", "msg": "<7>[    1.223884]     /sbin/init"}
- {"dt": "2025-03-25T10:29:38.457666", "lvl": "target", "msg": "<7>[    1.223917]     verbose"}
- {"dt": "2025-03-25T10:29:38.457732", "lvl": "target", "msg": "<7>[    1.223927]   with environment:"}
- {"dt": "2025-03-25T10:29:38.458103", "lvl": "target", "msg": "<7>[    1.223954]     HOME=/"}
- {"dt": "2025-03-25T10:29:38.458201", "lvl": "target", "msg": "<7>[    1.223973]     TERM=linux"}
- {"dt": "2025-03-25T10:29:38.783639", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2025-03-25T10:29:38.784039", "lvl": "target", "msg": "[0;1;39mWelcome to [0m[1mDebian GNU/Linux trixie/sid[0m[0;1;39m![0m"}
- {"dt": "2025-03-25T10:29:38.784121", "lvl": "target", "msg": "\r"}
- {"dt": "2025-03-25T10:29:40.502494", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2025-03-25T10:29:40.507881", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe.\r"}
- {"dt": "2025-03-25T10:29:40.512815", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty.\r"}
- {"dt": "2025-03-25T10:29:40.518527", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-xfs_scrub.sli\u2026[0mxfs_scrub background service slice.\r"}
- {"dt": "2025-03-25T10:29:40.522926", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2025-03-25T10:29:40.525095", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch.\r"}
- {"dt": "2025-03-25T10:29:40.526392", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch.\r"}
- {"dt": "2025-03-25T10:29:40.528383", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2025-03-25T10:29:40.529348", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2025-03-25T10:29:40.530454", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2025-03-25T10:29:40.531055", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2025-03-25T10:29:40.531852", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2025-03-25T10:29:40.543732", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption.\r"}
- {"dt": "2025-03-25T10:29:40.546358", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe.\r"}
- {"dt": "2025-03-25T10:29:40.548275", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2025-03-25T10:29:40.549973", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets.\r"}
- {"dt": "2025-03-25T10:29:40.552354", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket.\r"}
- {"dt": "2025-03-25T10:29:40.553808", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2025-03-25T10:29:40.576063", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System...\r"}
- {"dt": "2025-03-25T10:29:40.590933", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System...\r"}
- {"dt": "2025-03-25T10:29:40.604080", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock...\r"}
- {"dt": "2025-03-25T10:29:40.623578", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System...\r"}
- {"dt": "2025-03-25T10:29:40.659877", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp...\r"}
- {"dt": "2025-03-25T10:29:40.712731", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes.service[0m\u2026eate List of Static Device Nodes...\r"}
- {"dt": "2025-03-25T10:29:40.751889", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs...\r"}
- {"dt": "2025-03-25T10:29:40.796669", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2025-03-25T10:29:40.815841", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore...\r"}
- {"dt": "2025-03-25T10:29:40.839364", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse...\r"}
- {"dt": "2025-03-25T10:29:40.902745", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2025-03-25T10:29:40.920155", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules...\r"}
- {"dt": "2025-03-25T10:29:40.939560", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems...\r"}
- {"dt": "2025-03-25T10:29:40.964128", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials...\r"}
- {"dt": "2025-03-25T10:29:40.973532", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices...\r"}
- {"dt": "2025-03-25T10:29:41.010206", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System.\r"}
- {"dt": "2025-03-25T10:29:41.012642", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System.\r"}
- {"dt": "2025-03-25T10:29:41.014593", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock.\r"}
- {"dt": "2025-03-25T10:29:41.016665", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System.\r"}
- {"dt": "2025-03-25T10:29:41.018464", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp.\r"}
- {"dt": "2025-03-25T10:29:41.027244", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes.service[0m\u2026Create List of Static Device Nodes.\r"}
- {"dt": "2025-03-25T10:29:41.068037", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully...\r"}
- {"dt": "2025-03-25T10:29:41.280423", "lvl": "target", "msg": "<6>[    4.046442] fuse: init (API version 7.43)"}
- {"dt": "2025-03-25T10:29:41.398822", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems.\r"}
- {"dt": "2025-03-25T10:29:41.420387", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed...\r"}
- {"dt": "2025-03-25T10:29:41.436722", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules.\r"}
- {"dt": "2025-03-25T10:29:41.461481", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables...\r"}
- {"dt": "2025-03-25T10:29:41.474562", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2025-03-25T10:29:41.495121", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System...\r"}
- {"dt": "2025-03-25T10:29:41.575289", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs.\r"}
- {"dt": "2025-03-25T10:29:41.587560", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials.\r"}
- {"dt": "2025-03-25T10:29:41.613061", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2025-03-25T10:29:41.627774", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System...\r"}
- {"dt": "2025-03-25T10:29:41.639921", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System.\r"}
- {"dt": "2025-03-25T10:29:41.671416", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2025-03-25T10:29:41.681931", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully.\r"}
- {"dt": "2025-03-25T10:29:41.704366", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users...\r"}
- {"dt": "2025-03-25T10:29:41.748605", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System.\r"}
- {"dt": "2025-03-25T10:29:41.843079", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2025-03-25T10:29:41.849227", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed.\r"}
- {"dt": "2025-03-25T10:29:41.968304", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2025-03-25T10:29:41.992224", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage...\r"}
- {"dt": "2025-03-25T10:29:42.022954", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2025-03-25T10:29:42.191955", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev...\r"}
- {"dt": "2025-03-25T10:29:42.421287", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev.\r"}
- {"dt": "2025-03-25T10:29:42.436155", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems.\r"}
- {"dt": "2025-03-25T10:29:42.436978", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2025-03-25T10:29:42.453263", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management.\r"}
- {"dt": "2025-03-25T10:29:42.471506", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files...\r"}
- {"dt": "2025-03-25T10:29:42.482706", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage.\r"}
- {"dt": "2025-03-25T10:29:42.504880", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories...\r"}
- {"dt": "2025-03-25T10:29:42.907753", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories.\r"}
- {"dt": "2025-03-25T10:29:42.927632", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache...\r"}
- {"dt": "2025-03-25T10:29:42.947751", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog...\r"}
- {"dt": "2025-03-25T10:29:43.306366", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files.\r"}
- {"dt": "2025-03-25T10:29:43.607014", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog.\r"}
- {"dt": "2025-03-25T10:29:43.651006", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache.\r"}
- {"dt": "2025-03-25T10:29:43.675587", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed...\r"}
- {"dt": "2025-03-25T10:29:43.799165", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed.\r"}
- {"dt": "2025-03-25T10:29:44.901922", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices.\r"}
- {"dt": "2025-03-25T10:29:44.903910", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2025-03-25T10:29:44.908776", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2025-03-25T10:29:44.910665", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities.\r"}
- {"dt": "2025-03-25T10:29:44.912293", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer.\r"}
- {"dt": "2025-03-25T10:29:44.914779", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-03-25T10:29:44.917092", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week.\r"}
- {"dt": "2025-03-25T10:29:44.919287", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files.\r"}
- {"dt": "2025-03-25T10:29:44.920723", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories.\r"}
- {"dt": "2025-03-25T10:29:44.922990", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mxfs_scrub_all.timer[0m - Peri\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-03-25T10:29:44.923813", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2025-03-25T10:29:44.925695", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket.\r"}
- {"dt": "2025-03-25T10:29:44.927403", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket.\r"}
- {"dt": "2025-03-25T10:29:44.928083", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2025-03-25T10:29:44.929372", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2025-03-25T10:29:44.945643", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon.\r"}
- {"dt": "2025-03-25T10:29:44.963540", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2025-03-25T10:29:44.991803", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots...\r"}
- {"dt": "2025-03-25T10:29:45.039845", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management...\r"}
- {"dt": "2025-03-25T10:29:45.052502", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions...\r"}
- {"dt": "2025-03-25T10:29:45.366226", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2025-03-25T10:29:45.414111", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions.\r"}
- {"dt": "2025-03-25T10:29:45.444243", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2025-03-25T10:29:45.525896", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2025-03-25T10:29:45.541029", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAMA0.service[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2025-03-25T10:29:45.542684", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2025-03-25T10:29:45.763993", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots.\r"}
- {"dt": "2025-03-25T10:29:46.081674", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2025-03-25T10:29:46.259020", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2025-03-25T10:29:46.263586", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2025-03-25T10:29:48.013847", "lvl": "target", "msg": "[!p]104\a[?7h[6n[32766;32766H[6n[!p]104\a[?7h[6n[32766;32766H[6n\r"}
- {"dt": "2025-03-25T10:29:48.014273", "lvl": "target", "msg": "Debian GNU/Linux trixie/sid runner-ykxhnyexq-project-40964107-concurrent-0 ttyAMA0"}
- {"dt": "2025-03-25T10:29:48.014363", "lvl": "target", "msg": ""}
- {"dt": "2025-03-25T10:29:48.014739", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-03-25T10:29:48.014801", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "10.94", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-03-25T10:29:48.014888", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-03-25T10:29:48.014937", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-03-25T10:29:48.115170", "lvl": "input", "msg": "root"}
- {"dt": "2025-03-25T10:29:48.115299", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-03-25T10:29:48.115382", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:29:49)"}
- {"dt": "2025-03-25T10:29:48.115450", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:54)"}
- {"dt": "2025-03-25T10:29:48.115823", "lvl": "target", "msg": "runner-ykxhnyexq-project-40964107-concurrent-0 login: root\r"}
- {"dt": "2025-03-25T10:29:48.321104", "lvl": "target", "msg": "Linux runner-ykxhnyexq-project-40964107-concurrent-0 6.14.0-rc7-next-20250324 #1 SMP PREEMPT @1742818529 aarch64"}
- {"dt": "2025-03-25T10:29:48.324589", "lvl": "target", "msg": ""}
- {"dt": "2025-03-25T10:29:48.324888", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2025-03-25T10:29:48.324996", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2025-03-25T10:29:48.325123", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2025-03-25T10:29:48.325199", "lvl": "target", "msg": ""}
- {"dt": "2025-03-25T10:29:48.325439", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2025-03-25T10:29:48.325484", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2025-03-25T10:29:50.366355", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-03-25T10:29:50.366509", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:13) [common]"}
- {"dt": "2025-03-25T10:29:50.366581", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "13.29", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-03-25T10:29:50.366690", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:13) [common]"}
- {"dt": "2025-03-25T10:29:50.366761", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:29:47) [common]"}
- {"dt": "2025-03-25T10:29:50.366824", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-03-25T10:29:50.366877", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-03-25T10:29:50.366932", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-03-25T10:29:50.417069", "lvl": "input", "msg": ""}
- {"dt": "2025-03-25T10:29:50.417266", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:30:00)"}
- {"dt": "2025-03-25T10:29:50.417351", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-03-25T10:29:50.418479", "lvl": "target", "msg": "[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# "}
- {"dt": "2025-03-25T10:29:50.420091", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:50.420202", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:29:47) [common]"}
- {"dt": "2025-03-25T10:29:50.420322", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:29:50.420431", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:13) [common]"}
- {"dt": "2025-03-25T10:29:50.420535", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:50.420637", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:29:50.420719", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-03-25T10:29:50.420808", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-03-25T10:29:50.521039", "lvl": "input", "msg": "#"}
- {"dt": "2025-03-25T10:29:50.521192", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:05:00)"}
- {"dt": "2025-03-25T10:29:50.521647", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# #"}
- {"dt": "2025-03-25T10:29:50.523283", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-03-25T10:29:50.523358", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-03-25T10:29:50.623581", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-03-25T10:29:50.624523", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# export SHELL=/bin/sh"}
- {"dt": "2025-03-25T10:29:50.626307", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-03-25T10:29:50.726549", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-03-25T10:29:50.727396", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# . /lava-1/environment"}
- {"dt": "2025-03-25T10:29:50.731651", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-03-25T10:29:50.831884", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-03-25T10:29:50.832008", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-03-25T10:29:50.832750", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# /lava-1/bin/lava-test-runn\rner /lava-1/0"}
- {"dt": "2025-03-25T10:29:50.932320", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_kselftest-core"}
- {"dt": "2025-03-25T10:29:50.932707", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_kselftest-core"}
- {"dt": "2025-03-25T10:29:50.935810", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2025-03-25T10:29:50.943216", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-03-25T10:29:50.943480", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-03-25T10:29:50.944465", "lvl": "target", "msg": "+ cd ./automated/linux/kselftest/"}
- {"dt": "2025-03-25T10:29:50.945364", "lvl": "target", "msg": "<8>[   13.710804] <LAVA_SIGNAL_STARTRUN 0_kselftest-core 1_1.1.3.1>"}
- {"dt": "2025-03-25T10:29:50.945622", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_kselftest-core 1_1.1.3.1"}
- {"dt": "2025-03-25T10:29:50.945700", "lvl": "info", "msg": "Starting test lava.0_kselftest-core (1_1.1.3.1)"}
- {"dt": "2025-03-25T10:29:50.945862", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-03-25T10:29:50.949232", "lvl": "target", "msg": "+ ./kselftest.sh -c core -T  -t kselftest_armhf.tar.gz -s false -u  -L  -S skipfile-lkft.yaml -b  -g  -e production -p /opt/kselftests/default-in-kernel -n 1 -i 1 -E "}
- {"dt": "2025-03-25T10:29:50.996763", "lvl": "target", "msg": "INFO: Generating a skipfile based on /lava-1/0/tests/0_kselftest-core/automated/linux/kselftest/skipfile-lkft.yaml"}
- {"dt": "2025-03-25T10:29:51.147902", "lvl": "target", "msg": "INFO: Using the following generated skipfile contents (until EOF):"}
- {"dt": "2025-03-25T10:29:51.152016", "lvl": "target", "msg": "bpf:test_btf"}
- {"dt": "2025-03-25T10:29:51.152097", "lvl": "target", "msg": "bpf:test_progs"}
- {"dt": "2025-03-25T10:29:51.152157", "lvl": "target", "msg": "breakpoints:breakpoint_test"}
- {"dt": "2025-03-25T10:29:51.152222", "lvl": "target", "msg": "breakpoints:breakpoint_test_arm64"}
- {"dt": "2025-03-25T10:29:51.152277", "lvl": "target", "msg": "breakpoints:step_after_suspend_test"}
- {"dt": "2025-03-25T10:29:51.152324", "lvl": "target", "msg": "kmod:test_kmod.sh"}
- {"dt": "2025-03-25T10:29:51.152381", "lvl": "target", "msg": "kvm:get-reg-list"}
- {"dt": "2025-03-25T10:29:51.152429", "lvl": "target", "msg": "kvm:kvm_create_max_vcpus"}
- {"dt": "2025-03-25T10:29:51.152483", "lvl": "target", "msg": "mqueue:mq_open_tests"}
- {"dt": "2025-03-25T10:29:51.152530", "lvl": "target", "msg": "mqueue:mq_perf_tests"}
- {"dt": "2025-03-25T10:29:51.152586", "lvl": "target", "msg": "net:fib_nexthops.sh"}
- {"dt": "2025-03-25T10:29:51.152628", "lvl": "target", "msg": "net:msg_zerocopy.sh"}
- {"dt": "2025-03-25T10:29:51.152675", "lvl": "target", "msg": "net:rtnetlink.sh"}
- {"dt": "2025-03-25T10:29:51.152719", "lvl": "target", "msg": "net:run_afpackettests"}
- {"dt": "2025-03-25T10:29:51.152765", "lvl": "target", "msg": "net:tls"}
- {"dt": "2025-03-25T10:29:51.152809", "lvl": "target", "msg": "net:udpgro.sh"}
- {"dt": "2025-03-25T10:29:51.152853", "lvl": "target", "msg": "net:xfrm_policy.sh"}
- {"dt": "2025-03-25T10:29:51.152911", "lvl": "target", "msg": "netfilter:bridge_brouter.sh"}
- {"dt": "2025-03-25T10:29:51.153161", "lvl": "target", "msg": "netfilter:conntrack_icmp_related.sh"}
- {"dt": "2025-03-25T10:29:51.153216", "lvl": "target", "msg": "netfilter:nft_flowtable.sh"}
- {"dt": "2025-03-25T10:29:51.153260", "lvl": "target", "msg": "netfilter:nft_nat.sh"}
- {"dt": "2025-03-25T10:29:51.153312", "lvl": "target", "msg": "netfilter:nft_trans_stress.sh"}
- {"dt": "2025-03-25T10:29:51.153355", "lvl": "target", "msg": "pidfd:pidfd_wait"}
- {"dt": "2025-03-25T10:29:51.153401", "lvl": "target", "msg": "proc:proc-pid-vm"}
- {"dt": "2025-03-25T10:29:51.153440", "lvl": "target", "msg": "ptrace:vmaccess"}
- {"dt": "2025-03-25T10:29:51.153483", "lvl": "target", "msg": "rseq:run_param_test.sh"}
- {"dt": "2025-03-25T10:29:51.153524", "lvl": "target", "msg": "sync:sync_test"}
- {"dt": "2025-03-25T10:29:51.153567", "lvl": "target", "msg": "vm:run_vmtests"}
- {"dt": "2025-03-25T10:29:51.153609", "lvl": "target", "msg": "zram:zram.sh"}
- {"dt": "2025-03-25T10:29:51.155058", "lvl": "target", "msg": "INFO: EOF"}
- {"dt": "2025-03-25T10:29:51.206615", "lvl": "target", "msg": "INFO: Installing sed perl wget xz-utils iproute2 python3-tap"}
- {"dt": "2025-03-25T10:29:51.518939", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2025-03-25T10:29:52.524588", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2025-03-25T10:29:54.529915", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2025-03-25T10:29:58.535567", "lvl": "target", "msg": "Err:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2025-03-25T10:29:58.535717", "lvl": "target", "msg": "  Temporary failure resolving 'deb.debian.org'"}
- {"dt": "2025-03-25T10:29:58.641322", "lvl": "target", "msg": "Reading package lists..."}
- {"dt": "2025-03-25T10:29:58.658748", "lvl": "target", "msg": "W: Failed to fetch https://deb.debian.org/debian/dists/trixie/InRelease  Temporary failure resolving 'deb.debian.org'"}
- {"dt": "2025-03-25T10:29:58.659173", "lvl": "target", "msg": "W: Some index files failed to download. They have been ignored, or old ones used instead."}
- {"dt": "2025-03-25T10:29:58.745489", "lvl": "target", "msg": "Reading package lists..."}
- {"dt": "2025-03-25T10:29:58.768142", "lvl": "target", "msg": "Building dependency tree..."}
- {"dt": "2025-03-25T10:29:58.771490", "lvl": "target", "msg": "Reading state information..."}
- {"dt": "2025-03-25T10:29:58.790667", "lvl": "target", "msg": "sed is already the newest version (4.9-2+b1)."}
- {"dt": "2025-03-25T10:29:58.790895", "lvl": "target", "msg": "perl is already the newest version (5.40.0-8)."}
- {"dt": "2025-03-25T10:29:58.791121", "lvl": "target", "msg": "wget is already the newest version (1.24.5-2)."}
- {"dt": "2025-03-25T10:29:58.791448", "lvl": "target", "msg": "xz-utils is already the newest version (5.6.3-1+b1)."}
- {"dt": "2025-03-25T10:29:58.791534", "lvl": "target", "msg": "iproute2 is already the newest version (6.13.0-1)."}
- {"dt": "2025-03-25T10:29:58.791789", "lvl": "target", "msg": "python3-tap is already the newest version (3.2.1-1)."}
- {"dt": "2025-03-25T10:29:58.875932", "lvl": "target", "msg": "0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded."}
- {"dt": "2025-03-25T10:29:58.902926", "lvl": "target", "msg": "kselftests found on rootfs"}
- {"dt": "2025-03-25T10:29:58.936133", "lvl": "target", "msg": "skiplist:"}
- {"dt": "2025-03-25T10:29:58.936429", "lvl": "target", "msg": "========================================"}
- {"dt": "2025-03-25T10:29:58.936881", "lvl": "target", "msg": "bpf:test_btf"}
- {"dt": "2025-03-25T10:29:59.017359", "lvl": "target", "msg": "bpf:test_progs"}
- {"dt": "2025-03-25T10:29:59.038042", "lvl": "target", "msg": "breakpoints:breakpoint_test"}
- {"dt": "2025-03-25T10:29:59.057409", "lvl": "target", "msg": "breakpoints:breakpoint_test_arm64"}
- {"dt": "2025-03-25T10:29:59.080035", "lvl": "target", "msg": "breakpoints:step_after_suspend_test"}
- {"dt": "2025-03-25T10:29:59.100289", "lvl": "target", "msg": "kmod:test_kmod.sh"}
- {"dt": "2025-03-25T10:29:59.119904", "lvl": "target", "msg": "kvm:get-reg-list"}
- {"dt": "2025-03-25T10:29:59.139154", "lvl": "target", "msg": "kvm:kvm_create_max_vcpus"}
- {"dt": "2025-03-25T10:29:59.158618", "lvl": "target", "msg": "mqueue:mq_open_tests"}
- {"dt": "2025-03-25T10:29:59.178057", "lvl": "target", "msg": "mqueue:mq_perf_tests"}
- {"dt": "2025-03-25T10:29:59.197471", "lvl": "target", "msg": "net:fib_nexthops.sh"}
- {"dt": "2025-03-25T10:29:59.217400", "lvl": "target", "msg": "net:msg_zerocopy.sh"}
- {"dt": "2025-03-25T10:29:59.237186", "lvl": "target", "msg": "net:rtnetlink.sh"}
- {"dt": "2025-03-25T10:29:59.256864", "lvl": "target", "msg": "net:run_afpackettests"}
- {"dt": "2025-03-25T10:29:59.276177", "lvl": "target", "msg": "net:tls"}
- {"dt": "2025-03-25T10:29:59.296133", "lvl": "target", "msg": "net:udpgro.sh"}
- {"dt": "2025-03-25T10:29:59.315930", "lvl": "target", "msg": "net:xfrm_policy.sh"}
- {"dt": "2025-03-25T10:29:59.335798", "lvl": "target", "msg": "netfilter:bridge_brouter.sh"}
- {"dt": "2025-03-25T10:29:59.354865", "lvl": "target", "msg": "netfilter:conntrack_icmp_related.sh"}
- {"dt": "2025-03-25T10:29:59.373469", "lvl": "target", "msg": "netfilter:nft_flowtable.sh"}
- {"dt": "2025-03-25T10:29:59.393454", "lvl": "target", "msg": "netfilter:nft_nat.sh"}
- {"dt": "2025-03-25T10:29:59.412707", "lvl": "target", "msg": "netfilter:nft_trans_stress.sh"}
- {"dt": "2025-03-25T10:29:59.431785", "lvl": "target", "msg": "pidfd:pidfd_wait"}
- {"dt": "2025-03-25T10:29:59.451608", "lvl": "target", "msg": "proc:proc-pid-vm"}
- {"dt": "2025-03-25T10:29:59.471348", "lvl": "target", "msg": "ptrace:vmaccess"}
- {"dt": "2025-03-25T10:29:59.490915", "lvl": "target", "msg": "rseq:run_param_test.sh"}
- {"dt": "2025-03-25T10:29:59.510536", "lvl": "target", "msg": "sync:sync_test"}
- {"dt": "2025-03-25T10:29:59.529796", "lvl": "target", "msg": "vm:run_vmtests"}
- {"dt": "2025-03-25T10:29:59.549091", "lvl": "target", "msg": "zram:zram.sh"}
- {"dt": "2025-03-25T10:29:59.569347", "lvl": "target", "msg": "========================================"}
- {"dt": "2025-03-25T10:29:59.607713", "lvl": "target", "msg": "core:close_range_test"}
- {"dt": "2025-03-25T10:29:59.607802", "lvl": "target", "msg": "core:unshare_test"}
- {"dt": "2025-03-25T10:29:59.617732", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-03-25T10:29:59.621538", "lvl": "target", "msg": "core:close_range_test"}
- {"dt": "2025-03-25T10:29:59.621626", "lvl": "target", "msg": "core:unshare_test"}
- {"dt": "2025-03-25T10:29:59.622982", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-03-25T10:29:59.629475", "lvl": "target", "msg": "shardfile-core pass"}
- {"dt": "2025-03-25T10:29:59.753870", "lvl": "target", "msg": "<12>[   22.520307] kselftest: Running tests in core"}
- {"dt": "2025-03-25T10:29:59.770046", "lvl": "target", "msg": "TAP version 13"}
- {"dt": "2025-03-25T10:29:59.782920", "lvl": "target", "msg": "1..2"}
- {"dt": "2025-03-25T10:29:59.813211", "lvl": "target", "msg": "# timeout set to 45"}
- {"dt": "2025-03-25T10:29:59.813290", "lvl": "target", "msg": "# selftests: core: close_range_test"}
- {"dt": "2025-03-25T10:30:00.094202", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-03-25T10:30:00.094352", "lvl": "target", "msg": "# 1..9"}
- {"dt": "2025-03-25T10:30:00.094433", "lvl": "target", "msg": "# # Starting 9 tests from 1 test cases."}
- {"dt": "2025-03-25T10:30:00.095597", "lvl": "target", "msg": "# #  RUN           global.core_close_range ..."}
- {"dt": "2025-03-25T10:30:00.098016", "lvl": "target", "msg": "# #            OK  global.core_close_range"}
- {"dt": "2025-03-25T10:30:00.102070", "lvl": "target", "msg": "# ok 1 global.core_close_range"}
- {"dt": "2025-03-25T10:30:00.102150", "lvl": "target", "msg": "# #  RUN           global.close_range_unshare ..."}
- {"dt": "2025-03-25T10:30:00.103519", "lvl": "target", "msg": "# #            OK  global.close_range_unshare"}
- {"dt": "2025-03-25T10:30:00.106004", "lvl": "target", "msg": "# ok 2 global.close_range_unshare"}
- {"dt": "2025-03-25T10:30:00.110527", "lvl": "target", "msg": "# #  RUN           global.close_range_unshare_capped ..."}
- {"dt": "2025-03-25T10:30:00.110615", "lvl": "target", "msg": "# #            OK  global.close_range_unshare_capped"}
- {"dt": "2025-03-25T10:30:00.112336", "lvl": "target", "msg": "# ok 3 global.close_range_unshare_capped"}
- {"dt": "2025-03-25T10:30:00.118071", "lvl": "target", "msg": "# #  RUN           global.close_range_cloexec ..."}
- {"dt": "2025-03-25T10:30:00.118161", "lvl": "target", "msg": "# #            OK  global.close_range_cloexec"}
- {"dt": "2025-03-25T10:30:00.118234", "lvl": "target", "msg": "# ok 4 global.close_range_cloexec"}
- {"dt": "2025-03-25T10:30:00.118671", "lvl": "target", "msg": "# #  RUN           global.close_range_cloexec_unshare ..."}
- {"dt": "2025-03-25T10:30:00.123134", "lvl": "target", "msg": "# #            OK  global.close_range_cloexec_unshare"}
- {"dt": "2025-03-25T10:30:00.126053", "lvl": "target", "msg": "# ok 5 global.close_range_cloexec_unshare"}
- {"dt": "2025-03-25T10:30:00.126323", "lvl": "target", "msg": "# #  RUN           global.close_range_cloexec_syzbot ..."}
- {"dt": "2025-03-25T10:30:00.130022", "lvl": "target", "msg": "# #            OK  global.close_range_cloexec_syzbot"}
- {"dt": "2025-03-25T10:30:00.133275", "lvl": "target", "msg": "# ok 6 global.close_range_cloexec_syzbot"}
- {"dt": "2025-03-25T10:30:00.133362", "lvl": "target", "msg": "# #  RUN           global.close_range_cloexec_unshare_syzbot ..."}
- {"dt": "2025-03-25T10:30:00.162241", "lvl": "target", "msg": "# #            OK  global.close_range_cloexec_unshare_syzbot"}
- {"dt": "2025-03-25T10:30:00.162341", "lvl": "target", "msg": "# ok 7 global.close_range_cloexec_unshare_syzbot"}
- {"dt": "2025-03-25T10:30:00.168945", "lvl": "target", "msg": "# #  RUN           global.close_range_bitmap_corruption ..."}
- {"dt": "2025-03-25T10:30:00.169024", "lvl": "target", "msg": "# #            OK  global.close_range_bitmap_corruption"}
- {"dt": "2025-03-25T10:30:00.169106", "lvl": "target", "msg": "# ok 8 global.close_range_bitmap_corruption"}
- {"dt": "2025-03-25T10:30:00.169398", "lvl": "target", "msg": "# #  RUN           global.fcntl_created ..."}
- {"dt": "2025-03-25T10:30:00.202138", "lvl": "target", "msg": "# #            OK  global.fcntl_created"}
- {"dt": "2025-03-25T10:30:00.202813", "lvl": "target", "msg": "# ok 9 global.fcntl_created"}
- {"dt": "2025-03-25T10:30:00.205287", "lvl": "target", "msg": "# # PASSED: 9 / 9 tests passed."}
- {"dt": "2025-03-25T10:30:00.205374", "lvl": "target", "msg": "# # Totals: pass:9 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-03-25T10:30:00.216448", "lvl": "target", "msg": "ok 1 selftests: core: close_range_test"}
- {"dt": "2025-03-25T10:30:00.249388", "lvl": "target", "msg": "# timeout set to 45"}
- {"dt": "2025-03-25T10:30:00.249498", "lvl": "target", "msg": "# selftests: core: unshare_test"}
- {"dt": "2025-03-25T10:30:00.288533", "lvl": "target", "msg": "<4>[   23.054531] ------------[ cut here ]------------"}
- {"dt": "2025-03-25T10:30:00.288662", "lvl": "target", "msg": "<4>[   23.054625] WARNING: CPU: 0 PID: 475 at mm/slub.c:5028 __kvmalloc_node_noprof+0x408/0x490"}
- {"dt": "2025-03-25T10:30:00.289610", "lvl": "target", "msg": "<4>[   23.055555] Modules linked in: sm3_ce sm3 sha3_ce sha512_ce sha512_arm64 drm backlight fuse ip_tables x_tables"}
- {"dt": "2025-03-25T10:30:00.290242", "lvl": "target", "msg": "<4>[   23.056748] CPU: 0 UID: 0 PID: 475 Comm: unshare_test Not tainted 6.14.0-rc7-next-20250324 #1 PREEMPT "}
- {"dt": "2025-03-25T10:30:00.290462", "lvl": "target", "msg": "<4>[   23.057094] Hardware name: linux,dummy-virt (DT)"}
- {"dt": "2025-03-25T10:30:00.290856", "lvl": "target", "msg": "<4>[   23.057440] pstate: 23402009 (nzCv daif +PAN -UAO +TCO +DIT -SSBS BTYPE=--)"}
- {"dt": "2025-03-25T10:30:00.291093", "lvl": "target", "msg": "<4>[   23.057687] pc : __kvmalloc_node_noprof+0x408/0x490"}
- {"dt": "2025-03-25T10:30:00.291402", "lvl": "target", "msg": "<4>[   23.057908] lr : __kvmalloc_node_noprof+0x394/0x490"}
- {"dt": "2025-03-25T10:30:00.291455", "lvl": "target", "msg": "<4>[   23.058142] sp : ffff800080ac3c80"}
- {"dt": "2025-03-25T10:30:00.291813", "lvl": "target", "msg": "<4>[   23.058255] x29: ffff800080ac3cb0 x28: fff00000c6962500 x27: 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.292246", "lvl": "target", "msg": "<4>[   23.058658] x26: 0000000000000000 x25: 0000000000412cc0 x24: c9ffa101ca94f33c"}
- {"dt": "2025-03-25T10:30:00.292695", "lvl": "target", "msg": "<4>[   23.059034] x23: 0000000000000000 x22: 00000000ffffffff x21: 0000000000400cc0"}
- {"dt": "2025-03-25T10:30:00.292900", "lvl": "target", "msg": "<4>[   23.059408] x20: 0000000200001e00 x19: 0000000000000000 x18: 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.293206", "lvl": "target", "msg": "<4>[   23.059731] x17: 0000000000000000 x16: 0000000000000000 x15: 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.293508", "lvl": "target", "msg": "<4>[   23.060038] x14: 0000000000000000 x13: 0000000000000000 x12: 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.293904", "lvl": "target", "msg": "<4>[   23.060339] x11: 0000000000000000 x10: 0000000000000000 x9 : 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.294265", "lvl": "target", "msg": "<4>[   23.060733] x8 : 0000000000000001 x7 : 0000000000000001 x6 : 0000000000000005"}
- {"dt": "2025-03-25T10:30:00.294639", "lvl": "target", "msg": "<4>[   23.061105] x5 : 0000000000000000 x4 : fff00000c6962500 x3 : 0000000000000000"}
- {"dt": "2025-03-25T10:30:00.294983", "lvl": "target", "msg": "<4>[   23.061469] x2 : 0000000000000000 x1 : 0000000000000000 x0 : 000000007fffffff"}
- {"dt": "2025-03-25T10:30:00.295229", "lvl": "target", "msg": "<4>[   23.061905] Call trace:"}
- {"dt": "2025-03-25T10:30:00.295720", "lvl": "target", "msg": "<4>[   23.062201]  __kvmalloc_node_noprof+0x408/0x490 (P)"}
- {"dt": "2025-03-25T10:30:00.295795", "lvl": "target", "msg": "<4>[   23.062490]  alloc_fdtable+0x84/0x128"}
- {"dt": "2025-03-25T10:30:00.296083", "lvl": "target", "msg": "<4>[   23.062725]  expand_files+0x74/0x2ec"}
- {"dt": "2025-03-25T10:30:00.296418", "lvl": "target", "msg": "<4>[   23.062908]  ksys_dup3+0x60/0x120"}
- {"dt": "2025-03-25T10:30:00.296512", "lvl": "target", "msg": "<4>[   23.063098]  __arm64_sys_dup3+0x20/0x30"}
- {"dt": "2025-03-25T10:30:00.296785", "lvl": "target", "msg": "<4>[   23.063261]  invoke_syscall+0x48/0x10c"}
- {"dt": "2025-03-25T10:30:00.296871", "lvl": "target", "msg": "<4>[   23.063451]  el0_svc_common.constprop.0+0x40/0xe0"}
- {"dt": "2025-03-25T10:30:00.296947", "lvl": "target", "msg": "<4>[   23.063629]  do_el0_svc+0x1c/0x28"}
- {"dt": "2025-03-25T10:30:00.297060", "lvl": "target", "msg": "<4>[   23.063762]  el0_svc+0x30/0xcc"}
- {"dt": "2025-03-25T10:30:00.297380", "lvl": "target", "msg": "<4>[   23.063889]  el0t_64_sync_handler+0x10c/0x138"}
- {"dt": "2025-03-25T10:30:00.297433", "lvl": "target", "msg": "<4>[   23.064057]  el0t_64_sync+0x198/0x19c"}
- {"dt": "2025-03-25T10:30:00.297830", "lvl": "target", "msg": "<4>[   23.064318] ---[ end trace 0000000000000000 ]---"}
- {"dt": "2025-03-25T10:30:00.398903", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-03-25T10:30:00.399049", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-03-25T10:30:00.399845", "lvl": "target", "msg": "# # Starting 1 tests from 1 test cases."}
- {"dt": "2025-03-25T10:30:00.400793", "lvl": "target", "msg": "# #  RUN           global.unshare_EMFILE ..."}
- {"dt": "2025-03-25T10:30:00.402563", "lvl": "target", "msg": "# # unshare_test.c:60:unshare_EMFILE:Expected dup2(2, nr_open + 64) (-1) >= 0 (0)"}
- {"dt": "2025-03-25T10:30:00.403307", "lvl": "target", "msg": "# # unshare_EMFILE: Test failed"}
- {"dt": "2025-03-25T10:30:00.404039", "lvl": "target", "msg": "# #          FAIL  global.unshare_EMFILE"}
- {"dt": "2025-03-25T10:30:00.404772", "lvl": "target", "msg": "# not ok 1 global.unshare_EMFILE"}
- {"dt": "2025-03-25T10:30:00.405332", "lvl": "target", "msg": "# # FAILED: 0 / 1 tests passed."}
- {"dt": "2025-03-25T10:30:00.406495", "lvl": "target", "msg": "# # Totals: pass:0 fail:1 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-03-25T10:30:00.416490", "lvl": "target", "msg": "not ok 2 selftests: core: unshare_test # exit=1"}
- {"dt": "2025-03-25T10:30:01.348898", "lvl": "target", "msg": ""}
- {"dt": "2025-03-25T10:30:01.349286", "lvl": "target", "msg": "WARNING: Optional imports not found, TAP 13 output will be"}
- {"dt": "2025-03-25T10:30:01.349388", "lvl": "target", "msg": "    ignored. To parse yaml, see requirements in docs:"}
- {"dt": "2025-03-25T10:30:01.349474", "lvl": "target", "msg": "    https://tappy.readthedocs.io/en/latest/consumers.html#tap-version-13"}
- {"dt": "2025-03-25T10:30:01.356020", "lvl": "target", "msg": "Traceback (most recent call last):"}
- {"dt": "2025-03-25T10:30:01.367776", "lvl": "target", "msg": "  File [35m\"/lava-1/0/tests/0_kselftest-core/automated/linux/kselftest/./parse-output.py\"[0m, line [35m93[0m, in [35m<module>[0m"}
- {"dt": "2025-03-25T10:30:01.367871", "lvl": "target", "msg": "    results = parse_nested_tap(sys.stdin.read())"}
- {"dt": "2025-03-25T10:30:01.368349", "lvl": "target", "msg": "  File [35m\"/lava-1/0/tests/0_kselftest-core/automated/linux/kselftest/./parse-output.py\"[0m, line [35m51[0m, in [35mparse_nested_tap[0m"}
- {"dt": "2025-03-25T10:30:01.368397", "lvl": "target", "msg": "    \"children\": [31mparse_nested_tap[0m[1;31m(output)[0m,"}
- {"dt": "2025-03-25T10:30:01.368618", "lvl": "target", "msg": "                [31m~~~~~~~~~~~~~~~~[0m[1;31m^^^^^^^^[0m"}
- {"dt": "2025-03-25T10:30:01.369117", "lvl": "target", "msg": "  File [35m\"/lava-1/0/tests/0_kselftest-core/automated/linux/kselftest/./parse-output.py\"[0m, line [35m59[0m, in [35mparse_nested_tap[0m"}
- {"dt": "2025-03-25T10:30:01.369161", "lvl": "target", "msg": "    [31mr[0m[1;31m[\"logs\"][0m += f\"{uncomment(l.text)}\\n\""}
- {"dt": "2025-03-25T10:30:01.369206", "lvl": "target", "msg": "    [31m~[0m[1;31m^^^^^^^^[0m"}
- {"dt": "2025-03-25T10:30:01.369619", "lvl": "target", "msg": "[1;35mKeyError[0m: [35m'logs'[0m"}
- {"dt": "2025-03-25T10:30:01.437513", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-03-25T10:30:01.467957", "lvl": "target", "msg": "WARNING: no log file found for breakpoints:breakpoint_test_arm64 at ./output/breakpoints:breakpoint_test_arm64.log"}
- {"dt": "2025-03-25T10:30:01.483621", "lvl": "target", "msg": "<8>[   24.250016] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=breakpoints:breakpoint_test_arm64 RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.483932", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=breakpoints:breakpoint_test_arm64 RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.484041", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "breakpoints:breakpoint_test_arm64", "result": "skip", "starttc": ! "906", "endtc": ! "906"}}
- {"dt": "2025-03-25T10:30:01.496476", "lvl": "target", "msg": "WARNING: no log file found for breakpoints:step_after_suspend_test at ./output/breakpoints:step_after_suspend_test.log"}
- {"dt": "2025-03-25T10:30:01.510819", "lvl": "target", "msg": "<8>[   24.276989] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=breakpoints:step_after_suspend_test RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.511073", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=breakpoints:step_after_suspend_test RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.511153", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "breakpoints:step_after_suspend_test", "result": "skip", "starttc": ! "910", "endtc": ! "910"}}
- {"dt": "2025-03-25T10:30:01.522785", "lvl": "target", "msg": "WARNING: no log file found for kvm:get-reg-list at ./output/kvm:get-reg-list.log"}
- {"dt": "2025-03-25T10:30:01.537061", "lvl": "target", "msg": "<8>[   24.303495] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=kvm:get-reg-list RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.537327", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=kvm:get-reg-list RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.537438", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "kvm:get-reg-list", "result": "skip", "starttc": ! "914", "endtc": ! "914"}}
- {"dt": "2025-03-25T10:30:01.548862", "lvl": "target", "msg": "WARNING: no log file found for kvm:kvm_create_max_vcpus at ./output/kvm:kvm_create_max_vcpus.log"}
- {"dt": "2025-03-25T10:30:01.563483", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=kvm:kvm_create_max_vcpus RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.563586", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "kvm:kvm_create_max_vcpus", "result": "skip", "starttc": ! "917", "endtc": ! "917"}}
- {"dt": "2025-03-25T10:30:01.563733", "lvl": "target", "msg": "<8>[   24.329622] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=kvm:kvm_create_max_vcpus RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.575470", "lvl": "target", "msg": "WARNING: no log file found for mqueue:mq_open_tests at ./output/mqueue:mq_open_tests.log"}
- {"dt": "2025-03-25T10:30:01.589824", "lvl": "target", "msg": "<8>[   24.356137] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mqueue:mq_open_tests RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.590100", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=mqueue:mq_open_tests RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.590172", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "mqueue:mq_open_tests", "result": "skip", "starttc": ! "922", "endtc": ! "922"}}
- {"dt": "2025-03-25T10:30:01.602226", "lvl": "target", "msg": "WARNING: no log file found for mqueue:mq_perf_tests at ./output/mqueue:mq_perf_tests.log"}
- {"dt": "2025-03-25T10:30:01.617189", "lvl": "target", "msg": "<8>[   24.383354] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mqueue:mq_perf_tests RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.617458", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=mqueue:mq_perf_tests RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.617555", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "mqueue:mq_perf_tests", "result": "skip", "starttc": ! "926", "endtc": ! "926"}}
- {"dt": "2025-03-25T10:30:01.630135", "lvl": "target", "msg": "WARNING: no log file found for net:fib_nexthops.sh at ./output/net:fib_nexthops.sh.log"}
- {"dt": "2025-03-25T10:30:01.644592", "lvl": "target", "msg": "<8>[   24.411006] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:fib_nexthops.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.644912", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:fib_nexthops.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.645041", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:fib_nexthops.sh", "result": "skip", "starttc": ! "930", "endtc": ! "930"}}
- {"dt": "2025-03-25T10:30:01.656544", "lvl": "target", "msg": "WARNING: no log file found for net:msg_zerocopy.sh at ./output/net:msg_zerocopy.sh.log"}
- {"dt": "2025-03-25T10:30:01.671011", "lvl": "target", "msg": "<8>[   24.437315] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:msg_zerocopy.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.671278", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:msg_zerocopy.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.671374", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:msg_zerocopy.sh", "result": "skip", "starttc": ! "934", "endtc": ! "934"}}
- {"dt": "2025-03-25T10:30:01.683203", "lvl": "target", "msg": "WARNING: no log file found for net:rtnetlink.sh at ./output/net:rtnetlink.sh.log"}
- {"dt": "2025-03-25T10:30:01.697350", "lvl": "target", "msg": "<8>[   24.463661] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:rtnetlink.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.697635", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:rtnetlink.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.697720", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:rtnetlink.sh", "result": "skip", "starttc": ! "938", "endtc": ! "938"}}
- {"dt": "2025-03-25T10:30:01.709285", "lvl": "target", "msg": "WARNING: no log file found for net:run_afpackettests at ./output/net:run_afpackettests.log"}
- {"dt": "2025-03-25T10:30:01.724228", "lvl": "target", "msg": "<8>[   24.490453] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:run_afpackettests RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.724487", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:run_afpackettests RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.724576", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:run_afpackettests", "result": "skip", "starttc": ! "942", "endtc": ! "942"}}
- {"dt": "2025-03-25T10:30:01.736414", "lvl": "target", "msg": "WARNING: no log file found for net:tls at ./output/net:tls.log"}
- {"dt": "2025-03-25T10:30:01.751273", "lvl": "target", "msg": "<8>[   24.517707] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:tls RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.751536", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:tls RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.751601", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:tls", "result": "skip", "starttc": ! "946", "endtc": ! "946"}}
- {"dt": "2025-03-25T10:30:01.763524", "lvl": "target", "msg": "WARNING: no log file found for net:udpgro.sh at ./output/net:udpgro.sh.log"}
- {"dt": "2025-03-25T10:30:01.777997", "lvl": "target", "msg": "<8>[   24.544157] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:udpgro.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.778266", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:udpgro.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.778345", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:udpgro.sh", "result": "skip", "starttc": ! "950", "endtc": ! "950"}}
- {"dt": "2025-03-25T10:30:01.792072", "lvl": "target", "msg": "WARNING: no log file found for net:xfrm_policy.sh at ./output/net:xfrm_policy.sh.log"}
- {"dt": "2025-03-25T10:30:01.806789", "lvl": "target", "msg": "<8>[   24.573082] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:xfrm_policy.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.807043", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:xfrm_policy.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.807130", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "net:xfrm_policy.sh", "result": "skip", "starttc": ! "954", "endtc": ! "954"}}
- {"dt": "2025-03-25T10:30:01.819139", "lvl": "target", "msg": "WARNING: no log file found for pidfd:pidfd_wait at ./output/pidfd:pidfd_wait.log"}
- {"dt": "2025-03-25T10:30:01.832644", "lvl": "target", "msg": "<8>[   24.599053] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=pidfd:pidfd_wait RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.832908", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=pidfd:pidfd_wait RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.832987", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "pidfd:pidfd_wait", "result": "skip", "starttc": ! "958", "endtc": ! "958"}}
- {"dt": "2025-03-25T10:30:01.844481", "lvl": "target", "msg": "WARNING: no log file found for proc:proc-pid-vm at ./output/proc:proc-pid-vm.log"}
- {"dt": "2025-03-25T10:30:01.859203", "lvl": "target", "msg": "<8>[   24.625558] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=proc:proc-pid-vm RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.859471", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=proc:proc-pid-vm RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.859560", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "proc:proc-pid-vm", "result": "skip", "starttc": ! "962", "endtc": ! "962"}}
- {"dt": "2025-03-25T10:30:01.871156", "lvl": "target", "msg": "WARNING: no log file found for ptrace:vmaccess at ./output/ptrace:vmaccess.log"}
- {"dt": "2025-03-25T10:30:01.884573", "lvl": "target", "msg": "<8>[   24.650984] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ptrace:vmaccess RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.884839", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=ptrace:vmaccess RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.884924", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "ptrace:vmaccess", "result": "skip", "starttc": ! "966", "endtc": ! "966"}}
- {"dt": "2025-03-25T10:30:01.896352", "lvl": "target", "msg": "WARNING: no log file found for rseq:run_param_test.sh at ./output/rseq:run_param_test.sh.log"}
- {"dt": "2025-03-25T10:30:01.911066", "lvl": "target", "msg": "<8>[   24.677210] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=rseq:run_param_test.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.911394", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=rseq:run_param_test.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.911477", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "rseq:run_param_test.sh", "result": "skip", "starttc": ! "970", "endtc": ! "970"}}
- {"dt": "2025-03-25T10:30:01.923191", "lvl": "target", "msg": "WARNING: no log file found for sync:sync_test at ./output/sync:sync_test.log"}
- {"dt": "2025-03-25T10:30:01.937835", "lvl": "target", "msg": "<8>[   24.704032] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=sync:sync_test RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.938095", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=sync:sync_test RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.938163", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "sync:sync_test", "result": "skip", "starttc": ! "974", "endtc": ! "974"}}
- {"dt": "2025-03-25T10:30:01.950264", "lvl": "target", "msg": "WARNING: no log file found for zram:zram.sh at ./output/zram:zram.sh.log"}
- {"dt": "2025-03-25T10:30:01.964851", "lvl": "target", "msg": "<8>[   24.731191] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=zram:zram.sh RESULT=skip>"}
- {"dt": "2025-03-25T10:30:01.965298", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=zram:zram.sh RESULT=skip"}
- {"dt": "2025-03-25T10:30:01.965386", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "zram:zram.sh", "result": "skip", "starttc": ! "978", "endtc": ! "978"}}
- {"dt": "2025-03-25T10:30:01.976922", "lvl": "target", "msg": "WARNING: no log file found for shardfile-core at ./output/shardfile-core.log"}
- {"dt": "2025-03-25T10:30:01.991127", "lvl": "target", "msg": "<8>[   24.757559] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shardfile-core RESULT=pass>"}
- {"dt": "2025-03-25T10:30:01.991392", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shardfile-core RESULT=pass"}
- {"dt": "2025-03-25T10:30:01.991479", "lvl": "results", "msg": {"definition": "0_kselftest-core", "case": "shardfile-core", "result": "pass", "starttc": ! "982", "endtc": ! "982"}}
- {"dt": "2025-03-25T10:30:02.117063", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-03-25T10:30:02.117832", "lvl": "target", "msg": "<8>[   24.884120] <LAVA_SIGNAL_ENDRUN 0_kselftest-core 1_1.1.3.1>"}
- {"dt": "2025-03-25T10:30:02.118053", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_kselftest-core 1_1.1.3.1"}
- {"dt": "2025-03-25T10:30:02.118126", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-03-25T10:30:02.118177", "lvl": "info", "msg": "Ending test lava.0_kselftest-core (1_1.1.3.1), duration 11.17"}
- {"dt": "2025-03-25T10:30:02.118235", "lvl": "results", "msg": {"definition": "lava", "case": "0_kselftest-core", "uuid": "1_1.1.3.1", "repository": "https://github.com/TheodoreRTG/test-definitions.git", "path": "automated/linux/kselftest/kselftest.yaml", "duration": "11.17", "result": "pass", "revision": "unspecified", "namespace": "common", "commit_id": "450111580e70664ab07b21744eff0a1147707c44"}}
- {"dt": "2025-03-25T10:30:02.120358", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-03-25T10:30:02.120594", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-03-25T10:30:02.120802", "lvl": "debug", "msg": "breakpoints:breakpoint_test_arm64: skip\nbreakpoints:step_after_suspend_test: skip\nkvm:get-reg-list: skip\nkvm:kvm_create_max_vcpus: skip\nmqueue:mq_open_tests: skip\nmqueue:mq_perf_tests: skip\nnet:fib_nexthops.sh: skip\nnet:msg_zerocopy.sh: skip\nnet:rtnetlink.sh: skip\nnet:run_afpackettests: skip\nnet:tls: skip\nnet:udpgro.sh: skip\nnet:xfrm_policy.sh: skip\npidfd:pidfd_wait: skip\nproc:proc-pid-vm: skip\nptrace:vmaccess: skip\nrseq:run_param_test.sh: skip\nsync:sync_test: skip\nzram:zram.sh: skip\nshardfile-core: pass\n"}
- {"dt": "2025-03-25T10:30:02.120907", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:12) [common]"}
- {"dt": "2025-03-25T10:30:02.121000", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:12) [common]"}
- {"dt": "2025-03-25T10:30:02.121087", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:30:02.121176", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-03-25T10:30:02.121257", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-03-25T10:30:02.121333", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-03-25T10:30:02.121460", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-03-25T10:30:02.122828", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-03-25T10:30:02.123137", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-03-25T10:30:03.122761", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-03-25T10:30:03.122937", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-03-25T10:30:03.223748", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-03-25T10:30:03.224208", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-03-25T10:30:03.224295", "lvl": "target", "msg": "[?2004hroot@runner-ykxhnyexq-project-40964107-concurrent-0:~# poweroff"}
- {"dt": "2025-03-25T10:30:03.324700", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-03-25T10:30:03.324857", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-03-25T10:30:03.324939", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-03-25T10:30:03.325037", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-03-25T10:30:03.325153", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/kernel/Image"}
- {"dt": "2025-03-25T10:30:03.330950", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs/https___storage.tuxboot.com_debian_trixie_arm64_rootfs.ext4"}
- {"dt": "2025-03-25T10:30:03.441895", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.kselftest/kselftest.tar"}
- {"dt": "2025-03-25T10:30:03.455866", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1/deployimages-cy4vymgo/rootfs.modules/modules.tar"}
- {"dt": "2025-03-25T10:30:03.465422", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-ddewynk0-1"}
- {"dt": "2025-03-25T10:30:03.509116", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-03-25T10:30:03.509185", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
