- {"dt": "2025-07-03T15:04:27.232069", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0173"}
- {"dt": "2025-07-03T15:04:27.232293", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-07-03T15:04:27.232447", "lvl": "info", "msg": "Start time: 2025-07-03 15:04:27.232437+00:00 (UTC)"}
- {"dt": "2025-07-03T15:04:27.233590", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/196/zImage exists"}
- {"dt": "2025-07-03T15:04:27.233757", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4.xz exists"}
- {"dt": "2025-07-03T15:04:27.233845", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/196/modules.tar.xz exists"}
- {"dt": "2025-07-03T15:04:27.233932", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/overlays/debian/trixie/armhf/ltp/20250530/ltp.tar.xz exists"}
- {"dt": "2025-07-03T15:04:27.437183", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:10.0.0+ds-1, host architecture: amd64"}
- {"dt": "2025-07-03T15:04:27.437434", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2025-07-03T15:04:27.437491", "lvl": "warning", "msg": "Missing image arg for rootfs.overlay-00"}
- {"dt": "2025-07-03T15:04:27.437604", "lvl": "info", "msg": "validate duration: 0.21"}
- {"dt": "2025-07-03T15:04:27.437656", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-07-03T15:04:27.437750", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.437813", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.437963", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r"}
- {"dt": "2025-07-03T15:04:27.438088", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin"}
- {"dt": "2025-07-03T15:04:27.438187", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/tests"}
- {"dt": "2025-07-03T15:04:27.438274", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/results"}
- {"dt": "2025-07-03T15:04:27.438348", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-add-keys"}
- {"dt": "2025-07-03T15:04:27.438473", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-add-sources"}
- {"dt": "2025-07-03T15:04:27.438601", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-07-03T15:04:27.438738", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-07-03T15:04:27.438866", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-common-functions"}
- {"dt": "2025-07-03T15:04:27.438983", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-07-03T15:04:27.439098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-install-packages"}
- {"dt": "2025-07-03T15:04:27.439211", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-07-03T15:04:27.439327", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-os-build"}
- {"dt": "2025-07-03T15:04:27.439440", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-07-03T15:04:27.439553", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-07-03T15:04:27.439666", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-target-ip"}
- {"dt": "2025-07-03T15:04:27.439779", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-target-mac"}
- {"dt": "2025-07-03T15:04:27.439890", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-target-storage"}
- {"dt": "2025-07-03T15:04:27.440004", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-case"}
- {"dt": "2025-07-03T15:04:27.440118", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-event"}
- {"dt": "2025-07-03T15:04:27.440229", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-07-03T15:04:27.440340", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-raise"}
- {"dt": "2025-07-03T15:04:27.440453", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-reference"}
- {"dt": "2025-07-03T15:04:27.440570", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-runner"}
- {"dt": "2025-07-03T15:04:27.440681", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-set"}
- {"dt": "2025-07-03T15:04:27.440792", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-test-shell"}
- {"dt": "2025-07-03T15:04:27.440905", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-07-03T15:04:27.441062", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-07-03T15:04:27.441170", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/environment"}
- {"dt": "2025-07-03T15:04:27.441251", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-07-03T15:04:27.441300", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-07-03T15:04:27.441342", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-07-03T15:04:27.441415", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441461", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-07-03T15:04:27.441511", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441566", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441607", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-07-03T15:04:27.441654", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441705", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441752", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-07-03T15:04:27.441808", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:04:27.441853", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-07-03T15:04:27.441919", "lvl": "info", "msg": "Fetching tests from https://github.com/broonie/test-definitions.git"}
- {"dt": "2025-07-03T15:04:27.441976", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b ltp-json-performance --depth=1 https://github.com/broonie/test-definitions.git /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/0/tests/0_ltp-smoke'"}
- {"dt": "2025-07-03T15:04:30.818862", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.yaml"}
- {"dt": "2025-07-03T15:04:30.819492", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-07-03T15:04:30.819614", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2025-07-03T15:04:30.819733", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "git-repo-action", "level": "1.1.3.1", "duration": "3.38", "result": "pass", "extra": {"commit": "7150958affedbc2fde3f9f8aab5b1a7bf5703c9e", "repository": "https://github.com/broonie/test-definitions.git", "path": "automated/linux/ltp/ltp.yaml"}}}
- {"dt": "2025-07-03T15:04:30.819910", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-07-03T15:04:30.820710", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:30.820765", "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": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "git", "repository": "https://github.com/broonie/test-definitions.git"}}}
- {"dt": "2025-07-03T15:04:30.820936", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-07-03T15:04:30.821915", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:30.821972", "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": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "git", "repository": "https://github.com/broonie/test-definitions.git", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-07-03T15:04:30.822154", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-07-03T15:04:30.830306", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/0/tests/0_ltp-smoke test_uuid 1_1.1.3.1"}
- {"dt": "2025-07-03T15:04:30.830390", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2025-07-03T15:04:30.830472", "lvl": "debug", "msg": "TST_CMDFILES='smoketest'"}
- {"dt": "2025-07-03T15:04:30.830571", "lvl": "debug", "msg": "SKIPFILE='skipfile-lkft.yaml'"}
- {"dt": "2025-07-03T15:04:30.830665", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-07-03T15:04:30.830810", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2025-07-03T15:04:30.830884", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2025-07-03T15:04:30.830945", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-07-03T15:04:30.831000", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-07-03T15:04:30.831053", "lvl": "debug", "msg": "RUNNER='kirk'"}
- {"dt": "2025-07-03T15:04:30.831105", "lvl": "debug", "msg": "KIRK_WORKERS='1'"}
- {"dt": "2025-07-03T15:04:30.831154", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2025-07-03T15:04:30.831333", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:30.831412", "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": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "git", "repository": "https://github.com/broonie/test-definitions.git", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/0/tests/0_ltp-smoke/run.sh"}}}
- {"dt": "2025-07-03T15:04:30.831553", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-07-03T15:04:30.831612", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/lava-overlay-sjjfrz8r/lava-1/0 for stage 0"}
- {"dt": "2025-07-03T15:04:30.831717", "lvl": "debug", "msg": "- 0_ltp-smoke"}
- {"dt": "2025-07-03T15:04:30.831800", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:03) [common]"}
- {"dt": "2025-07-03T15:04:30.831860", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:14:57) [common]"}
- {"dt": "2025-07-03T15:04:36.695168", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-07-03T15:04:36.695296", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2025-07-03T15:04:36.695360", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:14:51) [common]"}
- {"dt": "2025-07-03T15:04:36.695414", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-07-03T15:04:36.695472", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:36.695522", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:14:51) [common]"}
- {"dt": "2025-07-03T15:04:36.695571", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:36.695621", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:51) [common]"}
- {"dt": "2025-07-03T15:04:36.695671", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:04:36.695816", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/196/zImage"}
- {"dt": "2025-07-03T15:04:36.695867", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/kernel/zImage"}
- {"dt": "2025-07-03T15:04:36.695908", "lvl": "debug", "msg": "total size: 15397376 (14\u202FMB)"}
- {"dt": "2025-07-03T15:04:36.695949", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-03T15:04:36.711375", "lvl": "info", "msg": "14\u202FMB downloaded in 0.02\u202Fs (949.96\u202FMB/s)"}
- {"dt": "2025-07-03T15:04:36.711526", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:36.711579", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.02", "result": "pass", "extra": {"label": "kernel", "size": ! "15397376", "sha256sum": "4e76740e96a303e401312ae8b6e11c69a6e2d401e3d96b340adcce68e37987fc"}}}
- {"dt": "2025-07-03T15:04:36.711737", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:36.711795", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:14:51) [common]"}
- {"dt": "2025-07-03T15:04:36.711848", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:04:36.711982", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4.xz"}
- {"dt": "2025-07-03T15:04:36.712032", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4"}
- {"dt": "2025-07-03T15:04:36.712075", "lvl": "debug", "msg": "total size: 133264556 (127\u202FMB)"}
- {"dt": "2025-07-03T15:04:36.712114", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-07-03T15:04:36.812635", "lvl": "debug", "msg": "progress   2\u202F% (2\u202FMB)"}
- {"dt": "2025-07-03T15:04:36.971743", "lvl": "debug", "msg": "progress   7\u202F% (8\u202FMB)"}
- {"dt": "2025-07-03T15:04:37.144872", "lvl": "debug", "msg": "progress  12\u202F% (15\u202FMB)"}
- {"dt": "2025-07-03T15:04:37.313985", "lvl": "debug", "msg": "progress  17\u202F% (21\u202FMB)"}
- {"dt": "2025-07-03T15:04:37.487037", "lvl": "debug", "msg": "progress  22\u202F% (27\u202FMB)"}
- {"dt": "2025-07-03T15:04:37.672761", "lvl": "debug", "msg": "progress  27\u202F% (34\u202FMB)"}
- {"dt": "2025-07-03T15:04:37.847581", "lvl": "debug", "msg": "progress  32\u202F% (40\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.025195", "lvl": "debug", "msg": "progress  37\u202F% (47\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.200157", "lvl": "debug", "msg": "progress  42\u202F% (53\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.375843", "lvl": "debug", "msg": "progress  47\u202F% (59\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.553855", "lvl": "debug", "msg": "progress  52\u202F% (66\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.748814", "lvl": "debug", "msg": "progress  57\u202F% (72\u202FMB)"}
- {"dt": "2025-07-03T15:04:38.921691", "lvl": "debug", "msg": "progress  62\u202F% (78\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.083987", "lvl": "debug", "msg": "progress  67\u202F% (85\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.267055", "lvl": "debug", "msg": "progress  72\u202F% (91\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.430866", "lvl": "debug", "msg": "progress  77\u202F% (97\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.594030", "lvl": "debug", "msg": "progress  82\u202F% (104\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.763308", "lvl": "debug", "msg": "progress  87\u202F% (110\u202FMB)"}
- {"dt": "2025-07-03T15:04:39.883422", "lvl": "debug", "msg": "progress  92\u202F% (116\u202FMB)"}
- {"dt": "2025-07-03T15:04:40.011626", "lvl": "debug", "msg": "progress  97\u202F% (123\u202FMB)"}
- {"dt": "2025-07-03T15:04:43.050934", "lvl": "info", "msg": "127\u202FMB downloaded in 6.34\u202Fs (20.05\u202FMB/s)"}
- {"dt": "2025-07-03T15:04:43.051174", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:06) [common]"}
- {"dt": "2025-07-03T15:04:43.051253", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "6.34", "result": "pass", "extra": {"label": "rootfs", "size": ! "133264556", "sha256sum": "a776e99ec5aa36cf4cb582b38406b0a32abe4c2c7a0965d928b7e36b93f78d53"}}}
- {"dt": "2025-07-03T15:04:43.051442", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:04:43.051576", "lvl": "debug", "msg": "start: 1.5.2.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-03T15:04:43.051789", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/196/modules.tar.xz"}
- {"dt": "2025-07-03T15:04:43.051864", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.modules/modules.tar"}
- {"dt": "2025-07-03T15:04:43.051923", "lvl": "debug", "msg": "total size: 7605520 (7\u202FMB)"}
- {"dt": "2025-07-03T15:04:43.051981", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-07-03T15:04:43.152579", "lvl": "debug", "msg": "progress  45\u202F% (3\u202FMB)"}
- {"dt": "2025-07-03T15:04:43.252681", "lvl": "debug", "msg": "progress  88\u202F% (6\u202FMB)"}
- {"dt": "2025-07-03T15:04:43.281702", "lvl": "info", "msg": "7\u202FMB downloaded in 0.23\u202Fs (31.57\u202FMB/s)"}
- {"dt": "2025-07-03T15:04:43.281880", "lvl": "debug", "msg": "end: 1.5.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:43.281969", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.2.1", "duration": "0.23", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "7605520", "sha256sum": "75a9ce2b5b185f83bec731062aa33b97a2f4110e3df20fc08e853387ebad8a2f"}}}
- {"dt": "2025-07-03T15:04:43.282120", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:43.282217", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:04:43.282297", "lvl": "debug", "msg": "start: 1.5.3.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-03T15:04:43.282453", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/overlays/debian/trixie/armhf/ltp/20250530/ltp.tar.xz"}
- {"dt": "2025-07-03T15:04:43.282503", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.overlay-00/ltp.tar"}
- {"dt": "2025-07-03T15:04:43.282544", "lvl": "debug", "msg": "total size: 22611908 (21\u202FMB)"}
- {"dt": "2025-07-03T15:04:43.282584", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-07-03T15:04:44.009364", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-07-03T15:04:44.438257", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2025-07-03T15:04:44.587444", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2025-07-03T15:04:44.728846", "lvl": "debug", "msg": "progress  15\u202F% (3\u202FMB)"}
- {"dt": "2025-07-03T15:04:44.849476", "lvl": "debug", "msg": "progress  20\u202F% (4\u202FMB)"}
- {"dt": "2025-07-03T15:04:44.962946", "lvl": "debug", "msg": "progress  25\u202F% (5\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.074491", "lvl": "debug", "msg": "progress  30\u202F% (6\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.178062", "lvl": "debug", "msg": "progress  35\u202F% (7\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.282264", "lvl": "debug", "msg": "progress  40\u202F% (8\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.383629", "lvl": "debug", "msg": "progress  45\u202F% (9\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.484931", "lvl": "debug", "msg": "progress  50\u202F% (10\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.586329", "lvl": "debug", "msg": "progress  55\u202F% (11\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.687883", "lvl": "debug", "msg": "progress  60\u202F% (13\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.789199", "lvl": "debug", "msg": "progress  65\u202F% (14\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.890492", "lvl": "debug", "msg": "progress  70\u202F% (15\u202FMB)"}
- {"dt": "2025-07-03T15:04:45.991784", "lvl": "debug", "msg": "progress  75\u202F% (16\u202FMB)"}
- {"dt": "2025-07-03T15:04:46.093266", "lvl": "debug", "msg": "progress  80\u202F% (17\u202FMB)"}
- {"dt": "2025-07-03T15:04:46.199331", "lvl": "debug", "msg": "progress  85\u202F% (18\u202FMB)"}
- {"dt": "2025-07-03T15:04:46.302117", "lvl": "debug", "msg": "progress  90\u202F% (19\u202FMB)"}
- {"dt": "2025-07-03T15:04:46.403416", "lvl": "debug", "msg": "progress  95\u202F% (20\u202FMB)"}
- {"dt": "2025-07-03T15:04:46.695110", "lvl": "info", "msg": "21\u202FMB downloaded in 3.41\u202Fs (6.32\u202FMB/s)"}
- {"dt": "2025-07-03T15:04:46.695331", "lvl": "debug", "msg": "end: 1.5.3.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2025-07-03T15:04:46.695396", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.3.1", "duration": "3.41", "result": "pass", "extra": {"label": "rootfs.overlay-00", "size": ! "22611908", "sha256sum": "530d4ae5bf533998a446adddc45836af8c124897ff9d839075da6473efc65ed1"}}}
- {"dt": "2025-07-03T15:04:46.695512", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:03) [common]"}
- {"dt": "2025-07-03T15:04:46.695581", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:04:46.695636", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4'"}
- {"dt": "2025-07-03T15:04:47.739134", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-07-03T15:04:47.739939", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/compress-overlay-1y4zyp3a/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-07-03T15:04:48.225272", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2025-07-03T15:04:48.308735", "lvl": "debug", "msg": "- rootfs.overlay-00: '/var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.overlay-00/ltp.tar' to '/'"}
- {"dt": "2025-07-03T15:04:49.526463", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2025-07-03T15:04:49.526865", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:13) [common]"}
- {"dt": "2025-07-03T15:04:49.526995", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:22) [common]"}
- {"dt": "2025-07-03T15:04:49.527119", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:30:00) [common]"}
- {"dt": "2025-07-03T15:04:49.527209", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:30:00) [common]"}
- {"dt": "2025-07-03T15:04:49.527278", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:30:00) [common]"}
- {"dt": "2025-07-03T15:04:49.527531", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-arm -cpu cortex-a15 -machine virt,gic-version=3 -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/kernel/zImage -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-9q9dcim4-1/deployimages-wzq0k3ed/rootfs/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-07-03T15:04:49.532478", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-07-03T15:04:49.532922", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:49.533094", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.01", "result": "pass", "extra": {"qemu_version": "1:10.0.0+ds-1", "host_arch": "amd64", "job_arch": "arm"}}}
- {"dt": "2025-07-03T15:04:49.533248", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:04:49.533328", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:30:00) [common]"}
- {"dt": "2025-07-03T15:04:49.533412", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-07-03T15:04:49.533474", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2025-07-03T15:04:49.962843", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0"}
- {"dt": "2025-07-03T15:04:49.963312", "lvl": "target", "msg": "[    0.000000] Linux version 6.16.0-rc4-00003-g1135b1f1b3f6 (tuxmake@tuxmake) (Debian clang version 20.1.7 (++20250616065708+6146a88f6049-1~exp1~20250616065826.132), Debian LLD 20.1.7) #1 SMP @1751276979"}
- {"dt": "2025-07-03T15:04:49.963481", "lvl": "target", "msg": "[    0.000000] CPU: ARMv7 Processor [414fc0f0] revision 0 (ARMv7), cr=30c5387d"}
- {"dt": "2025-07-03T15:04:49.963631", "lvl": "target", "msg": "[    0.000000] CPU: div instructions available: patching division code"}
- {"dt": "2025-07-03T15:04:49.963803", "lvl": "target", "msg": "[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"}
- {"dt": "2025-07-03T15:04:49.963936", "lvl": "target", "msg": "[    0.000000] OF: fdt: Machine model: linux,dummy-virt"}
- {"dt": "2025-07-03T15:04:49.964005", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2025-07-03T15:04:49.964170", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2025-07-03T15:04:49.964321", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2025-07-03T15:04:49.964585", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:30:00) [common]"}
- {"dt": "2025-07-03T15:04:49.964649", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-07-03T15:04:49.964706", "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-07-03T15:04:49.964760", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-07-03T15:04:49.964800", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-07-03T15:04:49.964840", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-07-03T15:04:49.964875", "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-07-03T15:04:49.964948", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:30:00)"}
- {"dt": "2025-07-03T15:04:49.964992", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-07-03T15:04:49.965356", "lvl": "target", "msg": "[    0.000000] Memory policy: Data cache writealloc"}
- {"dt": "2025-07-03T15:04:49.965414", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2025-07-03T15:04:49.967489", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fbe00000"}
- {"dt": "2025-07-03T15:04:49.969721", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2025-07-03T15:04:49.970270", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x000000006fffffff]"}
- {"dt": "2025-07-03T15:04:49.970342", "lvl": "target", "msg": "[    0.000000]   Normal   empty"}
- {"dt": "2025-07-03T15:04:49.970481", "lvl": "target", "msg": "[    0.000000]   HighMem  [mem 0x0000000070000000-0x000000013fffffff]"}
- {"dt": "2025-07-03T15:04:49.970646", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2025-07-03T15:04:49.970782", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2025-07-03T15:04:49.971001", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-07-03T15:04:49.971949", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-07-03T15:04:50.089601", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT"}
- {"dt": "2025-07-03T15:04:50.092732", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2025-07-03T15:04:50.093011", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-07-03T15:04:50.093096", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-07-03T15:04:50.093349", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2025-07-03T15:04:50.093514", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2025-07-03T15:04:50.095325", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 18 pages/cpu s42188 r8192 d23348 u73728"}
- {"dt": "2025-07-03T15:04:50.095819", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s42188 r8192 d23348 u73728 alloc=18*4096"}
- {"dt": "2025-07-03T15:04:50.096077", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-07-03T15:04:50.097370", "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-07-03T15:04:50.098510", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-07-03T15:04:50.099235", "lvl": "target", "msg": "<6>[    0.000000] printk: log buffer data + meta data: 131072 + 409600 = 540672 bytes"}
- {"dt": "2025-07-03T15:04:50.099792", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-07-03T15:04:50.100138", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-07-03T15:04:50.100417", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2025-07-03T15:04:50.153730", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x0000000069b0c000-0x000000006db0c000] (64MB)"}
- {"dt": "2025-07-03T15:04:50.154042", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2025-07-03T15:04:50.154493", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(pattern), heap alloc:off, heap free:off"}
- {"dt": "2025-07-03T15:04:50.177550", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-07-03T15:04:50.665758", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocating 66067 entries in 194 pages"}
- {"dt": "2025-07-03T15:04:50.665924", "lvl": "target", "msg": "<6>[    0.000000] ftrace: allocated 194 pages with 3 groups"}
- {"dt": "2025-07-03T15:04:50.668612", "lvl": "target", "msg": "<6>[    0.000000] BOOT_TIMING: ftrace_init completed"}
- {"dt": "2025-07-03T15:04:50.668700", "lvl": "target", "msg": "<6>[    0.000000] BOOT_TIMING: early_trace_init completed"}
- {"dt": "2025-07-03T15:04:50.669013", "lvl": "target", "msg": "<6>[    0.000000] BOOT_TIMING: scheduler_init starting"}
- {"dt": "2025-07-03T15:04:50.671785", "lvl": "target", "msg": "<6>[    0.000000] BOOT_TIMING: scheduler_init completed"}
- {"dt": "2025-07-03T15:04:50.674420", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2025-07-03T15:04:50.674511", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2025-07-03T15:04:50.674625", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."}
- {"dt": "2025-07-03T15:04:50.674933", "lvl": "target", "msg": "<6>[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2025-07-03T15:04:50.675020", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2025-07-03T15:04:50.675321", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."}
- {"dt": "2025-07-03T15:04:50.675487", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-07-03T15:04:50.676769", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2025-07-03T15:04:50.677234", "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-07-03T15:04:50.697742", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"}
- {"dt": "2025-07-03T15:04:50.705074", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2025-07-03T15:04:50.705147", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-07-03T15:04:50.706314", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-07-03T15:04:50.706407", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2025-07-03T15:04:50.707124", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2025-07-03T15:04:50.710861", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2025-07-03T15:04:50.711579", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @43170000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-07-03T15:04:50.711899", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @43180000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-07-03T15:04:50.712612", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000043190000"}
- {"dt": "2025-07-03T15:04:50.713367", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000431a0000"}
- {"dt": "2025-07-03T15:04:50.714772", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-07-03T15:04:50.728989", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt)."}
- {"dt": "2025-07-03T15:04:50.729568", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2025-07-03T15:04:50.730158", "lvl": "target", "msg": "<6>[    0.000085] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2025-07-03T15:04:50.730445", "lvl": "target", "msg": "<6>[    0.000411] Switching to timer-based delay loop, resolution 16ns"}
- {"dt": "2025-07-03T15:04:50.731076", "lvl": "target", "msg": "<6>[    0.000978] BOOT_TIMING: timekeeping_init completed"}
- {"dt": "2025-07-03T15:04:50.733489", "lvl": "target", "msg": "<6>[    0.003354] BOOT_TIMING: early_boot_complete"}
- {"dt": "2025-07-03T15:04:50.735025", "lvl": "target", "msg": "<6>[    0.004909] Console: colour dummy device 80x30"}
- {"dt": "2025-07-03T15:04:50.736503", "lvl": "target", "msg": "<6>[    0.006249] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"}
- {"dt": "2025-07-03T15:04:50.737464", "lvl": "target", "msg": "<6>[    0.006715] CPU: Testing write buffer coherency: ok"}
- {"dt": "2025-07-03T15:04:50.737639", "lvl": "target", "msg": "<3>[    0.007554] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2025-07-03T15:04:50.737929", "lvl": "target", "msg": "<6>[    0.007898] CPU0: Spectre BHB: enabling loop workaround for all CPUs"}
- {"dt": "2025-07-03T15:04:50.738184", "lvl": "target", "msg": "<6>[    0.008164] pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-07-03T15:04:50.742603", "lvl": "target", "msg": "<6>[    0.012493] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-07-03T15:04:50.742951", "lvl": "target", "msg": "<6>[    0.012719] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-07-03T15:04:50.763459", "lvl": "target", "msg": "<3>[    0.033228] /cpus/cpu@0 missing clock-frequency property"}
- {"dt": "2025-07-03T15:04:50.763581", "lvl": "target", "msg": "<3>[    0.033597] /cpus/cpu@1 missing clock-frequency property"}
- {"dt": "2025-07-03T15:04:50.763899", "lvl": "target", "msg": "<6>[    0.033845] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"}
- {"dt": "2025-07-03T15:04:50.764713", "lvl": "target", "msg": "<4>[    0.034647] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-07-03T15:04:50.772782", "lvl": "target", "msg": "<6>[    0.042671] Setting up static identity map for 0x40400000 - 0x40400184"}
- {"dt": "2025-07-03T15:04:50.774866", "lvl": "target", "msg": "<6>[    0.044761] rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-07-03T15:04:50.774943", "lvl": "target", "msg": "<6>[    0.044932] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-07-03T15:04:50.776860", "lvl": "target", "msg": "<6>[    0.046754] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2025-07-03T15:04:50.781242", "lvl": "target", "msg": "<6>[    0.051137] EFI services will not be available."}
- {"dt": "2025-07-03T15:04:50.783391", "lvl": "target", "msg": "<6>[    0.053314] smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-07-03T15:04:50.787302", "lvl": "target", "msg": "<6>[    0.056292] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2025-07-03T15:04:50.787535", "lvl": "target", "msg": "<6>[    0.056507] GICv3: CPU1: using allocated LPI pending table @0x00000000431b0000"}
- {"dt": "2025-07-03T15:04:50.787795", "lvl": "target", "msg": "<6>[    0.056928] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"}
- {"dt": "2025-07-03T15:04:50.788222", "lvl": "target", "msg": "<3>[    0.057167] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2025-07-03T15:04:50.791616", "lvl": "target", "msg": "<6>[    0.061530] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-07-03T15:04:50.792931", "lvl": "target", "msg": "<6>[    0.062856] SMP: Total of 2 processors activated (250.00 BogoMIPS)."}
- {"dt": "2025-07-03T15:04:50.793101", "lvl": "target", "msg": "<6>[    0.063129] CPU: All CPU(s) started in SVC mode."}
- {"dt": "2025-07-03T15:04:50.798832", "lvl": "target", "msg": "<6>[    0.068557] Memory: 3982432K/4194304K available (20480K kernel code, 2824K rwdata, 9904K rodata, 2048K init, 476K bss, 143044K reserved, 65536K cma-reserved, 3342336K highmem)"}
- {"dt": "2025-07-03T15:04:50.805604", "lvl": "target", "msg": "<6>[    0.075496] devtmpfs: initialized"}
- {"dt": "2025-07-03T15:04:50.818389", "lvl": "target", "msg": "<6>[    0.084841] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"}
- {"dt": "2025-07-03T15:04:50.838016", "lvl": "target", "msg": "<6>[    0.107864] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"}
- {"dt": "2025-07-03T15:04:50.838995", "lvl": "target", "msg": "<6>[    0.108884] posixtimers hash table entries: 1024 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-07-03T15:04:50.839481", "lvl": "target", "msg": "<6>[    0.109365] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear)."}
- {"dt": "2025-07-03T15:04:50.845195", "lvl": "target", "msg": "<6>[    0.115110] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2025-07-03T15:04:50.857811", "lvl": "target", "msg": "<6>[    0.127662] DMI not present or invalid."}
- {"dt": "2025-07-03T15:04:50.862537", "lvl": "target", "msg": "<6>[    0.132420] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-07-03T15:04:50.870871", "lvl": "target", "msg": "<6>[    0.140712] DMA: preallocated 256 KiB pool for atomic coherent allocations"}
- {"dt": "2025-07-03T15:04:50.875936", "lvl": "target", "msg": "<6>[    0.145393] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-07-03T15:04:50.876014", "lvl": "target", "msg": "<6>[    0.145817] cpuidle: using governor menu"}
- {"dt": "2025-07-03T15:04:50.876783", "lvl": "target", "msg": "<6>[    0.146697] No ATAGs?"}
- {"dt": "2025-07-03T15:04:50.877529", "lvl": "target", "msg": "<6>[    0.147422] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers."}
- {"dt": "2025-07-03T15:04:50.877744", "lvl": "target", "msg": "<6>[    0.147729] hw-breakpoint: maximum watchpoint size is 8 bytes."}
- {"dt": "2025-07-03T15:04:50.881948", "lvl": "target", "msg": "<6>[    0.151841] Serial: AMBA PL011 UART driver"}
- {"dt": "2025-07-03T15:04:50.920167", "lvl": "target", "msg": "<6>[    0.190031] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"}
- {"dt": "2025-07-03T15:04:50.922356", "lvl": "target", "msg": "<6>[    0.191761] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-07-03T15:04:50.922719", "lvl": "target", "msg": "<6>[    0.191761] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-07-03T15:04:50.923012", "lvl": "target", "msg": "<6>[    0.192980] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-07-03T15:04:50.923108", "lvl": "target", "msg": "<6>[    0.192980] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-07-03T15:04:50.937225", "lvl": "target", "msg": "<6>[    0.206531] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible."}
- {"dt": "2025-07-03T15:04:50.953494", "lvl": "target", "msg": "<6>[    0.222819] iommu: Default domain type: Translated"}
- {"dt": "2025-07-03T15:04:50.953601", "lvl": "target", "msg": "<6>[    0.222897] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2025-07-03T15:04:50.956163", "lvl": "target", "msg": "<5>[    0.225671] SCSI subsystem initialized"}
- {"dt": "2025-07-03T15:04:50.961811", "lvl": "target", "msg": "<7>[    0.226648] libata version 3.00 loaded."}
- {"dt": "2025-07-03T15:04:50.961898", "lvl": "target", "msg": "<6>[    0.227794] usbcore: registered new interface driver usbfs"}
- {"dt": "2025-07-03T15:04:50.962003", "lvl": "target", "msg": "<6>[    0.228060] usbcore: registered new interface driver hub"}
- {"dt": "2025-07-03T15:04:50.962094", "lvl": "target", "msg": "<6>[    0.228247] usbcore: registered new device driver usb"}
- {"dt": "2025-07-03T15:04:50.962181", "lvl": "target", "msg": "<6>[    0.230090] mc: Linux media interface: v0.10"}
- {"dt": "2025-07-03T15:04:50.962354", "lvl": "target", "msg": "<6>[    0.230294] videodev: Linux video capture interface: v2.00"}
- {"dt": "2025-07-03T15:04:50.962467", "lvl": "target", "msg": "<6>[    0.230556] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-07-03T15:04:50.962719", "lvl": "target", "msg": "<6>[    0.230599] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-07-03T15:04:50.962817", "lvl": "target", "msg": "<6>[    0.230716] PTP clock support registered"}
- {"dt": "2025-07-03T15:04:50.962913", "lvl": "target", "msg": "<6>[    0.231077] EDAC MC: Ver: 3.0.0"}
- {"dt": "2025-07-03T15:04:50.965114", "lvl": "target", "msg": "<6>[    0.233626] scmi_core: SCMI protocol bus registered"}
- {"dt": "2025-07-03T15:04:50.975083", "lvl": "target", "msg": "<6>[    0.244678] vgaarb: loaded"}
- {"dt": "2025-07-03T15:04:50.981599", "lvl": "target", "msg": "<6>[    0.248749] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-07-03T15:04:51.008478", "lvl": "target", "msg": "<6>[    0.268699] NET: Registered PF_INET protocol family"}
- {"dt": "2025-07-03T15:04:51.008584", "lvl": "target", "msg": "<6>[    0.269597] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.008707", "lvl": "target", "msg": "<6>[    0.274398] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.008893", "lvl": "target", "msg": "<6>[    0.274535] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.009091", "lvl": "target", "msg": "<6>[    0.274639] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.009292", "lvl": "target", "msg": "<6>[    0.274820] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.009456", "lvl": "target", "msg": "<6>[    0.275054] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2025-07-03T15:04:51.009659", "lvl": "target", "msg": "<6>[    0.276324] MPTCP token hash table entries: 1024 (order: 2, 16384 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.009824", "lvl": "target", "msg": "<6>[    0.276720] UDP hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.010008", "lvl": "target", "msg": "<6>[    0.276964] UDP-Lite hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-07-03T15:04:51.010170", "lvl": "target", "msg": "<6>[    0.277888] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-07-03T15:04:51.016176", "lvl": "target", "msg": "<6>[    0.282219] RPC: Registered named UNIX socket transport module."}
- {"dt": "2025-07-03T15:04:51.016258", "lvl": "target", "msg": "<6>[    0.282294] RPC: Registered udp transport module."}
- {"dt": "2025-07-03T15:04:51.016347", "lvl": "target", "msg": "<6>[    0.282337] RPC: Registered tcp transport module."}
- {"dt": "2025-07-03T15:04:51.016447", "lvl": "target", "msg": "<6>[    0.282375] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2025-07-03T15:04:51.016610", "lvl": "target", "msg": "<6>[    0.282411] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2025-07-03T15:04:51.016706", "lvl": "target", "msg": "<6>[    0.282511] PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-07-03T15:04:51.016817", "lvl": "target", "msg": "<5>[    0.285013] Initialise system trusted keyrings"}
- {"dt": "2025-07-03T15:04:51.023958", "lvl": "target", "msg": "<6>[    0.287418] workingset: timestamp_bits=14 max_order=20 bucket_order=6"}
- {"dt": "2025-07-03T15:04:51.024036", "lvl": "target", "msg": "<6>[    0.289356] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2025-07-03T15:04:51.026478", "lvl": "target", "msg": "<5>[    0.295100] NFS: Registering the id_resolver key type"}
- {"dt": "2025-07-03T15:04:51.026543", "lvl": "target", "msg": "<5>[    0.295381] Key type id_resolver registered"}
- {"dt": "2025-07-03T15:04:51.026631", "lvl": "target", "msg": "<5>[    0.295442] Key type id_legacy registered"}
- {"dt": "2025-07-03T15:04:51.026735", "lvl": "target", "msg": "<6>[    0.295722] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2025-07-03T15:04:51.026913", "lvl": "target", "msg": "<6>[    0.295814] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2025-07-03T15:04:51.027879", "lvl": "target", "msg": "<6>[    0.297268] fuse: init (API version 7.44)"}
- {"dt": "2025-07-03T15:04:51.099014", "lvl": "target", "msg": "<6>[    0.302589] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2025-07-03T15:04:51.136521", "lvl": "target", "msg": "<6>[    0.404982] NET: Registered PF_ALG protocol family"}
- {"dt": "2025-07-03T15:04:51.136607", "lvl": "target", "msg": "<5>[    0.405220] Key type asymmetric registered"}
- {"dt": "2025-07-03T15:04:51.136710", "lvl": "target", "msg": "<5>[    0.405297] Asymmetric key parser 'x509' registered"}
- {"dt": "2025-07-03T15:04:51.136799", "lvl": "target", "msg": "<6>[    0.405533] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2025-07-03T15:04:51.136885", "lvl": "target", "msg": "<6>[    0.405660] io scheduler mq-deadline registered"}
- {"dt": "2025-07-03T15:04:51.136983", "lvl": "target", "msg": "<6>[    0.405742] io scheduler kyber registered"}
- {"dt": "2025-07-03T15:04:51.180147", "lvl": "target", "msg": "<6>[    0.446394] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2025-07-03T15:04:51.229942", "lvl": "target", "msg": "<6>[    0.461501] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2025-07-03T15:04:51.230065", "lvl": "target", "msg": "<6>[    0.464913] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2025-07-03T15:04:51.230183", "lvl": "target", "msg": "<6>[    0.465594] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2025-07-03T15:04:51.230387", "lvl": "target", "msg": "<6>[    0.466124] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2025-07-03T15:04:51.230625", "lvl": "target", "msg": "<6>[    0.466219] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2025-07-03T15:04:51.231000", "lvl": "target", "msg": "<4>[    0.466688] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-07-03T15:04:51.231179", "lvl": "target", "msg": "<6>[    0.467060] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2025-07-03T15:04:51.231360", "lvl": "target", "msg": "<6>[    0.468425] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2025-07-03T15:04:51.231676", "lvl": "target", "msg": "<6>[    0.468690] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-07-03T15:04:51.231736", "lvl": "target", "msg": "<6>[    0.468775] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-07-03T15:04:51.231932", "lvl": "target", "msg": "<6>[    0.468838] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-07-03T15:04:51.232149", "lvl": "target", "msg": "<6>[    0.468889] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-07-03T15:04:51.232393", "lvl": "target", "msg": "<6>[    0.469885] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2025-07-03T15:04:51.232641", "lvl": "target", "msg": "<6>[    0.472966] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2025-07-03T15:04:51.232988", "lvl": "target", "msg": "<6>[    0.473284] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2025-07-03T15:04:51.233042", "lvl": "target", "msg": "<6>[    0.473383] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-07-03T15:04:51.233211", "lvl": "target", "msg": "<6>[    0.473447] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-07-03T15:04:51.233381", "lvl": "target", "msg": "<6>[    0.474796] PCI: bus0: Fast back to back transfers disabled"}
- {"dt": "2025-07-03T15:04:51.233594", "lvl": "target", "msg": "<6>[    0.476029] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2025-07-03T15:04:51.233778", "lvl": "target", "msg": "<6>[    0.476251] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2025-07-03T15:04:51.233939", "lvl": "target", "msg": "<6>[    0.476317] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2025-07-03T15:04:51.234086", "lvl": "target", "msg": "<6>[    0.476582] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2025-07-03T15:04:51.234248", "lvl": "target", "msg": "<6>[    0.476640] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-07-03T15:04:51.234418", "lvl": "target", "msg": "<6>[    0.476684] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-07-03T15:04:51.245999", "lvl": "target", "msg": "<6>[    0.507892] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103)"}
- {"dt": "2025-07-03T15:04:51.303848", "lvl": "target", "msg": "<6>[    0.572283] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"}
- {"dt": "2025-07-03T15:04:51.321893", "lvl": "target", "msg": "<6>[    0.579728] msm_serial: driver initialized"}
- {"dt": "2025-07-03T15:04:51.321984", "lvl": "target", "msg": "<6>[    0.580367] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2025-07-03T15:04:51.322086", "lvl": "target", "msg": "<6>[    0.580762] STMicroelectronics ASC driver initialized"}
- {"dt": "2025-07-03T15:04:51.322158", "lvl": "target", "msg": "<6>[    0.581069] STM32 USART driver initialized"}
- {"dt": "2025-07-03T15:04:51.346291", "lvl": "target", "msg": "<6>[    0.615855] brd: module loaded"}
- {"dt": "2025-07-03T15:04:51.358493", "lvl": "target", "msg": "<6>[    0.625759] loop: module loaded"}
- {"dt": "2025-07-03T15:04:51.358583", "lvl": "target", "msg": "<6>[    0.626566] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-07-03T15:04:51.366244", "lvl": "target", "msg": "<5>[    0.635645] virtio_blk virtio0: [vda] 5275328 512-byte logical blocks (2.70 GB/2.51 GiB)"}
- {"dt": "2025-07-03T15:04:51.384039", "lvl": "target", "msg": "<5>[    0.647294] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2025-07-03T15:04:51.384192", "lvl": "target", "msg": "<6>[    0.648496] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-07-03T15:04:51.384318", "lvl": "target", "msg": "<6>[    0.648788] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-07-03T15:04:51.384414", "lvl": "target", "msg": "<6>[    0.649465] Using buffer write method"}
- {"dt": "2025-07-03T15:04:51.384571", "lvl": "target", "msg": "<7>[    0.649751] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-07-03T15:04:51.384802", "lvl": "target", "msg": "<5>[    0.650996] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2025-07-03T15:04:51.385060", "lvl": "target", "msg": "<6>[    0.651469] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-07-03T15:04:51.385217", "lvl": "target", "msg": "<6>[    0.651523] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-07-03T15:04:51.385332", "lvl": "target", "msg": "<6>[    0.651972] Using buffer write method"}
- {"dt": "2025-07-03T15:04:51.385498", "lvl": "target", "msg": "<7>[    0.652015] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-07-03T15:04:51.385618", "lvl": "target", "msg": "<5>[    0.652094] Concatenating MTD devices:"}
- {"dt": "2025-07-03T15:04:51.385709", "lvl": "target", "msg": "<5>[    0.652140] (0): \"0.flash\""}
- {"dt": "2025-07-03T15:04:51.385803", "lvl": "target", "msg": "<5>[    0.652186] (1): \"0.flash\""}
- {"dt": "2025-07-03T15:04:51.385913", "lvl": "target", "msg": "<5>[    0.652222] into device \"0.flash\""}
- {"dt": "2025-07-03T15:04:51.430645", "lvl": "target", "msg": "<6>[    0.676707] CAN device driver interface"}
- {"dt": "2025-07-03T15:04:51.430751", "lvl": "target", "msg": "<6>[    0.678480] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"}
- {"dt": "2025-07-03T15:04:51.430856", "lvl": "target", "msg": "<6>[    0.679884] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-07-03T15:04:51.430948", "lvl": "target", "msg": "<6>[    0.679926] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2025-07-03T15:04:51.431033", "lvl": "target", "msg": "<6>[    0.680088] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2025-07-03T15:04:51.431183", "lvl": "target", "msg": "<6>[    0.680128] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2025-07-03T15:04:51.431320", "lvl": "target", "msg": "<6>[    0.685749] pegasus: Pegasus/Pegasus II USB Ethernet driver"}
- {"dt": "2025-07-03T15:04:51.431458", "lvl": "target", "msg": "<6>[    0.685888] usbcore: registered new interface driver pegasus"}
- {"dt": "2025-07-03T15:04:51.431606", "lvl": "target", "msg": "<6>[    0.686019] usbcore: registered new interface driver asix"}
- {"dt": "2025-07-03T15:04:51.431758", "lvl": "target", "msg": "<6>[    0.686147] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2025-07-03T15:04:51.431914", "lvl": "target", "msg": "<6>[    0.686274] usbcore: registered new interface driver cdc_ether"}
- {"dt": "2025-07-03T15:04:51.432096", "lvl": "target", "msg": "<6>[    0.686410] usbcore: registered new interface driver smsc75xx"}
- {"dt": "2025-07-03T15:04:51.432274", "lvl": "target", "msg": "<6>[    0.686538] usbcore: registered new interface driver smsc95xx"}
- {"dt": "2025-07-03T15:04:51.432448", "lvl": "target", "msg": "<6>[    0.686663] usbcore: registered new interface driver net1080"}
- {"dt": "2025-07-03T15:04:51.432630", "lvl": "target", "msg": "<6>[    0.686789] usbcore: registered new interface driver cdc_subset"}
- {"dt": "2025-07-03T15:04:51.433000", "lvl": "target", "msg": "<6>[    0.686925] usbcore: registered new interface driver zaurus"}
- {"dt": "2025-07-03T15:04:51.433048", "lvl": "target", "msg": "<6>[    0.687063] usbcore: registered new interface driver cdc_ncm"}
- {"dt": "2025-07-03T15:04:51.433182", "lvl": "target", "msg": "<6>[    0.693002] usbcore: registered new interface driver usb-storage"}
- {"dt": "2025-07-03T15:04:51.464971", "lvl": "target", "msg": "<6>[    0.704136] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2025-07-03T15:04:51.465074", "lvl": "target", "msg": "<6>[    0.704825] rtc-pl031 9010000.pl031: setting system clock to 2025-07-03T15:04:51 UTC (1751555091)"}
- {"dt": "2025-07-03T15:04:51.465179", "lvl": "target", "msg": "<6>[    0.707052] i2c_dev: i2c /dev entries driver"}
- {"dt": "2025-07-03T15:04:51.465331", "lvl": "target", "msg": "<6>[    0.725412] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2025-07-03T15:04:51.465455", "lvl": "target", "msg": "<6>[    0.725457] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2025-07-03T15:04:51.465635", "lvl": "target", "msg": "<6>[    0.727948] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2025-07-03T15:04:51.465773", "lvl": "target", "msg": "<6>[    0.729999] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2025-07-03T15:04:51.482835", "lvl": "target", "msg": "<6>[    0.741530] usbcore: registered new interface driver usbhid"}
- {"dt": "2025-07-03T15:04:51.482910", "lvl": "target", "msg": "<6>[    0.741592] usbhid: USB HID core driver"}
- {"dt": "2025-07-03T15:04:51.482984", "lvl": "target", "msg": "<6>[    0.748025]  cs_system_cfg: CoreSight Configuration manager initialised"}
- {"dt": "2025-07-03T15:04:51.483134", "lvl": "target", "msg": "<6>[    0.751642] NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-07-03T15:04:51.505105", "lvl": "target", "msg": "<6>[    0.757757] Segment Routing with IPv6"}
- {"dt": "2025-07-03T15:04:51.505192", "lvl": "target", "msg": "<6>[    0.757955] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-07-03T15:04:51.505272", "lvl": "target", "msg": "<6>[    0.758336] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-07-03T15:04:51.505342", "lvl": "target", "msg": "<6>[    0.760552] NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-07-03T15:04:51.505420", "lvl": "target", "msg": "<6>[    0.760620] can: controller area network core"}
- {"dt": "2025-07-03T15:04:51.505556", "lvl": "target", "msg": "<6>[    0.760916] NET: Registered PF_CAN protocol family"}
- {"dt": "2025-07-03T15:04:51.505652", "lvl": "target", "msg": "<6>[    0.761026] can: raw protocol"}
- {"dt": "2025-07-03T15:04:51.505787", "lvl": "target", "msg": "<6>[    0.761167] can: broadcast manager protocol"}
- {"dt": "2025-07-03T15:04:51.505912", "lvl": "target", "msg": "<6>[    0.761308] can: netlink gateway - max_hops=1"}
- {"dt": "2025-07-03T15:04:51.506032", "lvl": "target", "msg": "<5>[    0.762291] Key type dns_resolver registered"}
- {"dt": "2025-07-03T15:04:51.506161", "lvl": "target", "msg": "<6>[    0.762730] ThumbEE CPU extension supported."}
- {"dt": "2025-07-03T15:04:51.506301", "lvl": "target", "msg": "<5>[    0.762822] Registering SWP/SWPB emulation handler"}
- {"dt": "2025-07-03T15:04:51.509544", "lvl": "target", "msg": "<6>[    0.777136] registered taskstats version 1"}
- {"dt": "2025-07-03T15:04:51.509611", "lvl": "target", "msg": "<5>[    0.777231] Loading compiled-in X.509 certificates"}
- {"dt": "2025-07-03T15:04:51.530541", "lvl": "target", "msg": "<6>[    0.796121] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2025-07-03T15:04:52.067669", "lvl": "target", "msg": "<6>[    1.337274] clk: Disabling unused clocks"}
- {"dt": "2025-07-03T15:04:52.068116", "lvl": "target", "msg": "<6>[    1.337798] PM: genpd: Disabling unused power domains"}
- {"dt": "2025-07-03T15:04:52.097654", "lvl": "target", "msg": "<6>[    1.367236] EXT4-fs (vda): mounted filesystem a8313b7e-e018-474a-89c7-0a5d8b7e7700 r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-03T15:04:52.098818", "lvl": "target", "msg": "<6>[    1.368658] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2025-07-03T15:04:52.100418", "lvl": "target", "msg": "<6>[    1.370231] devtmpfs: mounted"}
- {"dt": "2025-07-03T15:04:52.257387", "lvl": "target", "msg": "<6>[    1.495474] Freeing unused kernel image (initmem) memory: 2048K"}
- {"dt": "2025-07-03T15:04:52.257813", "lvl": "target", "msg": "<6>[    1.527587] Run /sbin/init as init process"}
- {"dt": "2025-07-03T15:04:52.257899", "lvl": "target", "msg": "<7>[    1.527679]   with arguments:"}
- {"dt": "2025-07-03T15:04:52.258026", "lvl": "target", "msg": "<7>[    1.527742]     /sbin/init"}
- {"dt": "2025-07-03T15:04:52.258188", "lvl": "target", "msg": "<7>[    1.527794]     verbose"}
- {"dt": "2025-07-03T15:04:52.258507", "lvl": "target", "msg": "<7>[    1.527832]   with environment:"}
- {"dt": "2025-07-03T15:04:52.258554", "lvl": "target", "msg": "<7>[    1.527853]     HOME=/"}
- {"dt": "2025-07-03T15:04:52.258622", "lvl": "target", "msg": "<7>[    1.527872]     TERM=linux"}
- {"dt": "2025-07-03T15:04:52.664576", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2025-07-03T15:04:52.664991", "lvl": "target", "msg": "[0;1;39mWelcome to [0m[1mDebian GNU/Linux 13 (trixie)[0m[0;1;39m![0m"}
- {"dt": "2025-07-03T15:04:52.665085", "lvl": "target", "msg": "\r"}
- {"dt": "2025-07-03T15:04:54.615633", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2025-07-03T15:04:54.624151", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe.\r"}
- {"dt": "2025-07-03T15:04:54.632173", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty.\r"}
- {"dt": "2025-07-03T15:04:54.640340", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-xfs_scrub.sli\u2026[0mxfs_scrub background service slice.\r"}
- {"dt": "2025-07-03T15:04:54.646333", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2025-07-03T15:04:54.649733", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch.\r"}
- {"dt": "2025-07-03T15:04:54.651882", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch.\r"}
- {"dt": "2025-07-03T15:04:54.654450", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2025-07-03T15:04:54.655852", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork.target[0m - Network.\r"}
- {"dt": "2025-07-03T15:04:54.656876", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online.\r"}
- {"dt": "2025-07-03T15:04:54.658189", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups.\r"}
- {"dt": "2025-07-03T15:04:54.659193", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2025-07-03T15:04:54.660282", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2025-07-03T15:04:54.661248", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2025-07-03T15:04:54.668630", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mrpcbind.socket[0m - RPCbind Server Activation Socket.\r"}
- {"dt": "2025-07-03T15:04:54.680240", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption.\r"}
- {"dt": "2025-07-03T15:04:54.682683", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe.\r"}
- {"dt": "2025-07-03T15:04:54.687055", "lvl": "target", "msg": "<28>[    3.955637] systemd[1]: systemd-journald-dev-log.socket: SO_PASSSEC failed: Operation not supported"}
- {"dt": "2025-07-03T15:04:54.688174", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2025-07-03T15:04:54.690082", "lvl": "target", "msg": "<28>[    3.959615] systemd[1]: systemd-journald.socket: SO_PASSSEC failed: Operation not supported"}
- {"dt": "2025-07-03T15:04:54.690801", "lvl": "target", "msg": "<28>[    3.960541] systemd[1]: systemd-journald.socket: SO_PASSSEC failed: Operation not supported"}
- {"dt": "2025-07-03T15:04:54.691372", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets.\r"}
- {"dt": "2025-07-03T15:04:54.694239", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket.\r"}
- {"dt": "2025-07-03T15:04:54.696151", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2025-07-03T15:04:54.726881", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System...\r"}
- {"dt": "2025-07-03T15:04:54.759151", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock...\r"}
- {"dt": "2025-07-03T15:04:54.783960", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System...\r"}
- {"dt": "2025-07-03T15:04:54.819212", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System...\r"}
- {"dt": "2025-07-03T15:04:54.989290", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp...\r"}
- {"dt": "2025-07-03T15:04:55.041204", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes.service[0m\u2026eate List of Static Device Nodes...\r"}
- {"dt": "2025-07-03T15:04:55.059886", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs...\r"}
- {"dt": "2025-07-03T15:04:55.100134", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2025-07-03T15:04:55.120071", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore...\r"}
- {"dt": "2025-07-03T15:04:55.154701", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse...\r"}
- {"dt": "2025-07-03T15:04:55.168149", "lvl": "target", "msg": "<28>[    4.428469] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling."}
- {"dt": "2025-07-03T15:04:55.168490", "lvl": "target", "msg": "<28>[    4.428656] systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.)"}
- {"dt": "2025-07-03T15:04:55.230387", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2025-07-03T15:04:55.250136", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules...\r"}
- {"dt": "2025-07-03T15:04:55.279929", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems...\r"}
- {"dt": "2025-07-03T15:04:55.309765", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials...\r"}
- {"dt": "2025-07-03T15:04:55.339970", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices...\r"}
- {"dt": "2025-07-03T15:04:55.390223", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System.\r"}
- {"dt": "2025-07-03T15:04:55.394627", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock.\r"}
- {"dt": "2025-07-03T15:04:55.397315", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System.\r"}
- {"dt": "2025-07-03T15:04:55.400051", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System.\r"}
- {"dt": "2025-07-03T15:04:55.403084", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp.\r"}
- {"dt": "2025-07-03T15:04:55.481007", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes.service[0m\u2026Create List of Static Device Nodes.\r"}
- {"dt": "2025-07-03T15:04:55.548612", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully...\r"}
- {"dt": "2025-07-03T15:04:55.740279", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules.\r"}
- {"dt": "2025-07-03T15:04:55.756073", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems.\r"}
- {"dt": "2025-07-03T15:04:55.781498", "lvl": "target", "msg": "         Mounting [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem...\r"}
- {"dt": "2025-07-03T15:04:55.821527", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed...\r"}
- {"dt": "2025-07-03T15:04:55.850639", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables...\r"}
- {"dt": "2025-07-03T15:04:55.969572", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2025-07-03T15:04:55.981763", "lvl": "target", "msg": "<28>[    5.251468] systemd[1]: proc-fs-nfsd.mount: Failed with result 'exit-code'."}
- {"dt": "2025-07-03T15:04:55.999287", "lvl": "target", "msg": "<27>[    5.269001] systemd[1]: Failed to mount proc-fs-nfsd.mount - NFSD configuration filesystem."}
- {"dt": "2025-07-03T15:04:55.999913", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem.\r"}
- {"dt": "2025-07-03T15:04:56.000930", "lvl": "target", "msg": "See 'systemctl status proc-fs-nfsd.mount' for details.\r"}
- {"dt": "2025-07-03T15:04:56.001752", "lvl": "target", "msg": "<28>[    5.271452] systemd[1]: Dependency failed for nfsdcld.service - NFSv4 Client Tracking Daemon."}
- {"dt": "2025-07-03T15:04:56.002362", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfsdcld.service[0m - NFSv4 Client Tracking Daemon.\r"}
- {"dt": "2025-07-03T15:04:56.003362", "lvl": "target", "msg": "<28>[    5.272829] systemd[1]: Dependency failed for nfs-mountd.service - NFS Mount Daemon."}
- {"dt": "2025-07-03T15:04:56.003652", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-mountd.service[0m - NFS Mount Daemon.\r"}
- {"dt": "2025-07-03T15:04:56.004770", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-server.service[0m - NFS server and services.\r<28>[    5.274060] systemd[1]: Dependency failed for nfs-server.service - NFS server and services."}
- {"dt": "2025-07-03T15:04:56.004834", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:04:56.005508", "lvl": "target", "msg": "<28>[    5.275231] systemd[1]: Dependency failed for nfs-idmapd.service - NFSv4 ID-name mapping service."}
- {"dt": "2025-07-03T15:04:56.006179", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-idmapd.s\u2026ce[0m - NFSv4 ID-name mapping service.\r"}
- {"dt": "2025-07-03T15:04:56.023208", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2025-07-03T15:04:56.043032", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials.\r"}
- {"dt": "2025-07-03T15:04:56.079265", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System...\r"}
- {"dt": "2025-07-03T15:04:56.217923", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs.\r"}
- {"dt": "2025-07-03T15:04:56.245697", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2025-07-03T15:04:56.261966", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System.\r"}
- {"dt": "2025-07-03T15:04:56.388638", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System...\r"}
- {"dt": "2025-07-03T15:04:56.435220", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2025-07-03T15:04:56.468701", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed.\r"}
- {"dt": "2025-07-03T15:04:56.591046", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully.\r"}
- {"dt": "2025-07-03T15:04:56.631314", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users...\r"}
- {"dt": "2025-07-03T15:04:56.647059", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System.\r"}
- {"dt": "2025-07-03T15:04:56.683516", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2025-07-03T15:04:56.730185", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage...\r"}
- {"dt": "2025-07-03T15:04:57.063300", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2025-07-03T15:04:57.087302", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev...\r"}
- {"dt": "2025-07-03T15:04:57.250125", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage.\r"}
- {"dt": "2025-07-03T15:04:57.383379", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev.\r"}
- {"dt": "2025-07-03T15:04:57.394405", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems.\r"}
- {"dt": "2025-07-03T15:04:57.395560", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2025-07-03T15:04:57.411525", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management.\r"}
- {"dt": "2025-07-03T15:04:57.429370", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart...\r"}
- {"dt": "2025-07-03T15:04:57.449788", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories...\r"}
- {"dt": "2025-07-03T15:04:57.500381", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files...\r"}
- {"dt": "2025-07-03T15:04:57.719609", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart.\r"}
- {"dt": "2025-07-03T15:04:58.170301", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories.\r"}
- {"dt": "2025-07-03T15:04:58.219566", "lvl": "target", "msg": "         Mounting [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System...\r"}
- {"dt": "2025-07-03T15:04:58.242629", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache...\r"}
- {"dt": "2025-07-03T15:04:58.271613", "lvl": "target", "msg": "         Starting [0;1;39mrpcbind.service[0m - RPC bind portmap service...\r"}
- {"dt": "2025-07-03T15:04:58.311103", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog...\r"}
- {"dt": "2025-07-03T15:04:58.539402", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files.\r"}
- {"dt": "2025-07-03T15:04:58.574782", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System.\r"}
- {"dt": "2025-07-03T15:04:58.581341", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpc_pipefs.target[0m.\r"}
- {"dt": "2025-07-03T15:04:58.619380", "lvl": "target", "msg": "         Starting [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon...\r"}
- {"dt": "2025-07-03T15:04:58.620598", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnfs-client.target[0m - NFS client services.\r"}
- {"dt": "2025-07-03T15:04:58.690908", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpcbind.service[0m - RPC bind portmap service.\r"}
- {"dt": "2025-07-03T15:04:58.693168", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs-pre.targe\u2026[0mreparation for Remote File Systems.\r"}
- {"dt": "2025-07-03T15:04:58.695825", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2025-07-03T15:04:58.697013", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpcbind.target[0m - RPC Port Mapper.\r"}
- {"dt": "2025-07-03T15:04:58.729313", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd.service[0m - NFS s\u2026tus monitor for NFSv2/3 locking....\r"}
- {"dt": "2025-07-03T15:04:58.824148", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon.\r"}
- {"dt": "2025-07-03T15:04:59.165950", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd.service[0m - NFS status monitor for NFSv2/3 locking..\r"}
- {"dt": "2025-07-03T15:04:59.359625", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog.\r"}
- {"dt": "2025-07-03T15:04:59.405239", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache.\r"}
- {"dt": "2025-07-03T15:04:59.429913", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed...\r"}
- {"dt": "2025-07-03T15:04:59.619238", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed.\r"}
- {"dt": "2025-07-03T15:05:01.872055", "lvl": "target", "msg": "[[0m[0;31m*     [0m] (1 of 2) Job dev-ttyAMA0.device/start running (7s / 13min 30s)\r"}
- {"dt": "2025-07-03T15:05:02.455226", "lvl": "target", "msg": "M\r[K[[0;1;31m*[0m[0;31m*    [0m] (1 of 2) Job dev-ttyAMA0.device/start running (7s / 13min 30s)\r"}
- {"dt": "2025-07-03T15:05:03.038937", "lvl": "target", "msg": "M\r[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] (1 of 2) Job dev-ttyAMA0.device/start running (8s / 13min 30s)\r"}
- {"dt": "2025-07-03T15:05:03.622988", "lvl": "target", "msg": "M\r[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] (2 of 2) Job systemd-udev-trigger.s\u2026vice/start running (9s / no limit)\r"}
- {"dt": "2025-07-03T15:05:04.206332", "lvl": "target", "msg": "M\r[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 2) Job systemd-udev-trigger.s\u2026vice/start running (9s / no limit)\r"}
- {"dt": "2025-07-03T15:05:04.546776", "lvl": "target", "msg": "M\r[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (10s / no limit)\r"}
- {"dt": "2025-07-03T15:05:05.006192", "lvl": "target", "msg": "M\r[K[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices.\r"}
- {"dt": "2025-07-03T15:05:05.013201", "lvl": "target", "msg": "[K[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2025-07-03T15:05:05.026275", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2025-07-03T15:05:05.039590", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities.\r"}
- {"dt": "2025-07-03T15:05:05.042144", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer.\r"}
- {"dt": "2025-07-03T15:05:05.045578", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-07-03T15:05:05.049383", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week.\r"}
- {"dt": "2025-07-03T15:05:05.051892", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files.\r"}
- {"dt": "2025-07-03T15:05:05.053864", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories.\r"}
- {"dt": "2025-07-03T15:05:05.059282", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mxfs_scrub_all.timer[0m - Peri\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-07-03T15:05:05.068583", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2025-07-03T15:05:05.071154", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket.\r"}
- {"dt": "2025-07-03T15:05:05.073703", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket.\r"}
- {"dt": "2025-07-03T15:05:05.075837", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket.\r"}
- {"dt": "2025-07-03T15:05:05.076751", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2025-07-03T15:05:05.079351", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2025-07-03T15:05:05.109966", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon.\r"}
- {"dt": "2025-07-03T15:05:05.126808", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2025-07-03T15:05:05.165252", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots...\r"}
- {"dt": "2025-07-03T15:05:05.200070", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfsidd.service[0m - NFS FSID Daemon.\r"}
- {"dt": "2025-07-03T15:05:05.230354", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management...\r"}
- {"dt": "2025-07-03T15:05:05.259951", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions...\r"}
- {"dt": "2025-07-03T15:05:05.778606", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions.\r"}
- {"dt": "2025-07-03T15:05:05.831669", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2025-07-03T15:05:05.852200", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2025-07-03T15:05:06.231037", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots.\r"}
- {"dt": "2025-07-03T15:05:06.516067", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2025-07-03T15:05:06.642718", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2025-07-03T15:05:06.681112", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAMA0.service[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2025-07-03T15:05:06.684084", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2025-07-03T15:05:06.689580", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2025-07-03T15:05:06.691928", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2025-07-03T15:05:08.696077", "lvl": "target", "msg": "[!p]104\a[?7h[6n[32766;32766H[6n[!p]104\a[?7h[6n[32766;32766H[6n\r"}
- {"dt": "2025-07-03T15:05:08.696501", "lvl": "target", "msg": "Debian GNU/Linux 13 runner-ns46nmmj-project-40964107-concurrent-0 ttyAMA0"}
- {"dt": "2025-07-03T15:05:08.696611", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:08.697111", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-07-03T15:05:08.697228", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "18.73", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-07-03T15:05:08.697389", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-07-03T15:05:08.697456", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-07-03T15:05:08.798044", "lvl": "input", "msg": "root"}
- {"dt": "2025-07-03T15:05:08.798188", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-03T15:05:08.798279", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:29:41)"}
- {"dt": "2025-07-03T15:05:08.798350", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:50)"}
- {"dt": "2025-07-03T15:05:08.801359", "lvl": "target", "msg": "runner-ns46nmmj-project-40964107-concurrent-0 login: root\r"}
- {"dt": "2025-07-03T15:05:09.399625", "lvl": "target", "msg": "Linux runner-ns46nmmj-project-40964107-concurrent-0 6.16.0-rc4-00003-g1135b1f1b3f6 #1 SMP @1751276979 armv7l"}
- {"dt": "2025-07-03T15:05:09.405333", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:09.405629", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2025-07-03T15:05:09.405719", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2025-07-03T15:05:09.405839", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2025-07-03T15:05:09.405925", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:09.406206", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2025-07-03T15:05:09.406255", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2025-07-03T15:05:16.370368", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-03T15:05:16.370575", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:26) [common]"}
- {"dt": "2025-07-03T15:05:16.370662", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "26.41", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-07-03T15:05:16.370846", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:27) [common]"}
- {"dt": "2025-07-03T15:05:16.370953", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:29:33) [common]"}
- {"dt": "2025-07-03T15:05:16.371039", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-03T15:05:16.371106", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-07-03T15:05:16.371173", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-07-03T15:05:16.421319", "lvl": "input", "msg": ""}
- {"dt": "2025-07-03T15:05:16.421483", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:30:00)"}
- {"dt": "2025-07-03T15:05:16.421565", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-07-03T15:05:16.422591", "lvl": "target", "msg": "[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# "}
- {"dt": "2025-07-03T15:05:16.425441", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:05:16.425548", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:29:33) [common]"}
- {"dt": "2025-07-03T15:05:16.425658", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:05:16.425758", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:27) [common]"}
- {"dt": "2025-07-03T15:05:16.425855", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:05:16.425961", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:15:00) [common]"}
- {"dt": "2025-07-03T15:05:16.426049", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-07-03T15:05:16.426128", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-07-03T15:05:16.526382", "lvl": "input", "msg": "#"}
- {"dt": "2025-07-03T15:05:16.526618", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2025-07-03T15:05:16.527042", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# #"}
- {"dt": "2025-07-03T15:05:16.529441", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-07-03T15:05:16.529521", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-07-03T15:05:16.629747", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-07-03T15:05:16.630416", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# export SHELL=/bin/sh"}
- {"dt": "2025-07-03T15:05:16.632931", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-07-03T15:05:16.733211", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-07-03T15:05:16.733830", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# . /lava-1/environment"}
- {"dt": "2025-07-03T15:05:16.737409", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-07-03T15:05:16.837655", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-07-03T15:05:16.837814", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-07-03T15:05:16.838641", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# /lava-1/bin/lava-test-runne\rer /lava-1/0"}
- {"dt": "2025-07-03T15:05:17.093492", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-07-03T15:05:17.093892", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-07-03T15:05:17.107984", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2025-07-03T15:05:17.149377", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-07-03T15:05:17.149668", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-07-03T15:05:17.150519", "lvl": "target", "msg": "<8>[   26.420310] <LAVA_SIGNAL_STARTRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-07-03T15:05:17.150721", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-07-03T15:05:17.150789", "lvl": "info", "msg": "Starting test lava.0_ltp-smoke (1_1.1.3.1)"}
- {"dt": "2025-07-03T15:05:17.150859", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-07-03T15:05:17.150943", "lvl": "target", "msg": "+ cd ./automated/linux/ltp/"}
- {"dt": "2025-07-03T15:05:17.155923", "lvl": "target", "msg": "+ ./ltp.sh -T smoketest -s true -v  -M 5 -R root -r kirk -b  -d /scratch -g  -e production -i /opt/ltp/ -S skipfile-lkft.yaml -p  -u  -t false -n 1 -c 1 -w 1"}
- {"dt": "2025-07-03T15:05:17.174533", "lvl": "target", "msg": "+ . ../../lib/sh-test-lib"}
- {"dt": "2025-07-03T15:05:17.176867", "lvl": "target", "msg": "++ LANG=C"}
- {"dt": "2025-07-03T15:05:17.177116", "lvl": "target", "msg": "++ export LANG"}
- {"dt": "2025-07-03T15:05:17.201862", "lvl": "target", "msg": "++ export PATH=/lava-1/0/../bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-07-03T15:05:17.202310", "lvl": "target", "msg": "++ PATH=/lava-1/0/../bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-07-03T15:05:17.207927", "lvl": "target", "msg": "++ kernel_config="}
- {"dt": "2025-07-03T15:05:17.223425", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-07-03T15:05:17.226929", "lvl": "target", "msg": "+ OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-07-03T15:05:17.228051", "lvl": "target", "msg": "+ RESULT_FILE=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/result.txt"}
- {"dt": "2025-07-03T15:05:17.235851", "lvl": "target", "msg": "++ readlink -f ./ltp.sh"}
- {"dt": "2025-07-03T15:05:17.261192", "lvl": "target", "msg": "+ SCRIPT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-07-03T15:05:17.273287", "lvl": "target", "msg": "++ dirname /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-07-03T15:05:17.288546", "lvl": "target", "msg": "+ SCRIPTPATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-07-03T15:05:17.289580", "lvl": "target", "msg": "+ echo 'Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp'"}
- {"dt": "2025-07-03T15:05:17.290153", "lvl": "target", "msg": "Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-07-03T15:05:17.290378", "lvl": "target", "msg": "+ TST_CMDFILES="}
- {"dt": "2025-07-03T15:05:17.290601", "lvl": "target", "msg": "+ SKIPFILE="}
- {"dt": "2025-07-03T15:05:17.290823", "lvl": "target", "msg": "+ SKIPFILE_YAML="}
- {"dt": "2025-07-03T15:05:17.291047", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-07-03T15:05:17.291102", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-07-03T15:05:17.291322", "lvl": "target", "msg": "+ ENVIRONMENT="}
- {"dt": "2025-07-03T15:05:17.291544", "lvl": "target", "msg": "+ LTP_VERSION=20180926"}
- {"dt": "2025-07-03T15:05:17.291766", "lvl": "target", "msg": "+ TEST_PROGRAM=ltp"}
- {"dt": "2025-07-03T15:05:17.291996", "lvl": "target", "msg": "+ TEST_GIT_URL="}
- {"dt": "2025-07-03T15:05:17.299809", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-07-03T15:05:17.303696", "lvl": "target", "msg": "+ TEST_DIR=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp"}
- {"dt": "2025-07-03T15:05:17.304351", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-07-03T15:05:17.304446", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-07-03T15:05:17.304707", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-07-03T15:05:17.304804", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-07-03T15:05:17.305051", "lvl": "target", "msg": "+ KIRK_WORKERS=1"}
- {"dt": "2025-07-03T15:05:17.305271", "lvl": "target", "msg": "+ LTP_TMPDIR=/ltp-tmp"}
- {"dt": "2025-07-03T15:05:17.305489", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp"}
- {"dt": "2025-07-03T15:05:17.309979", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.310517", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.310814", "lvl": "target", "msg": "+ TST_CMDFILES=smoketest"}
- {"dt": "2025-07-03T15:05:17.325344", "lvl": "target", "msg": "++ echo smoketest"}
- {"dt": "2025-07-03T15:05:17.331821", "lvl": "target", "msg": "++ sed 's,\\/,_,'"}
- {"dt": "2025-07-03T15:05:17.371663", "lvl": "target", "msg": "+ LOG_FILE=smoketest"}
- {"dt": "2025-07-03T15:05:17.372171", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.372598", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.372998", "lvl": "target", "msg": "+ SKIP_INSTALL=true"}
- {"dt": "2025-07-03T15:05:17.373274", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.373337", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.373760", "lvl": "target", "msg": "+ LTP_VERSION="}
- {"dt": "2025-07-03T15:05:17.374061", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.374117", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.374538", "lvl": "target", "msg": "+ export LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-07-03T15:05:17.374586", "lvl": "target", "msg": "+ LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-07-03T15:05:17.374969", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.375195", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.375427", "lvl": "target", "msg": "+ export PASSWD=root"}
- {"dt": "2025-07-03T15:05:17.375474", "lvl": "target", "msg": "+ PASSWD=root"}
- {"dt": "2025-07-03T15:05:17.375689", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.375912", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.376132", "lvl": "target", "msg": "+ export RUNNER=kirk"}
- {"dt": "2025-07-03T15:05:17.376374", "lvl": "target", "msg": "+ RUNNER=kirk"}
- {"dt": "2025-07-03T15:05:17.376628", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.376681", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.377063", "lvl": "target", "msg": "+ export BOARD="}
- {"dt": "2025-07-03T15:05:17.377111", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-07-03T15:05:17.377505", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.377891", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.378139", "lvl": "target", "msg": "+ export LTP_TMPDIR=/scratch"}
- {"dt": "2025-07-03T15:05:17.378186", "lvl": "target", "msg": "+ LTP_TMPDIR=/scratch"}
- {"dt": "2025-07-03T15:05:17.378408", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.378631", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.378854", "lvl": "target", "msg": "+ export BRANCH="}
- {"dt": "2025-07-03T15:05:17.379081", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-07-03T15:05:17.379489", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.379544", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.379930", "lvl": "target", "msg": "+ export ENVIRONMENT=production"}
- {"dt": "2025-07-03T15:05:17.379984", "lvl": "target", "msg": "+ ENVIRONMENT=production"}
- {"dt": "2025-07-03T15:05:17.380202", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.380422", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.380643", "lvl": "target", "msg": "+ export LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-07-03T15:05:17.380864", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-07-03T15:05:17.381083", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.381303", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.382236", "lvl": "target", "msg": "+ '[' -z skipfile-lkft.yaml ']'"}
- {"dt": "2025-07-03T15:05:17.382628", "lvl": "target", "msg": "+ '[' yaml = yaml ']'"}
- {"dt": "2025-07-03T15:05:17.383025", "lvl": "target", "msg": "+ SKIPFILE_YAML=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml"}
- {"dt": "2025-07-03T15:05:17.383438", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.383492", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.384232", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-07-03T15:05:17.384622", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.384677", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.384896", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-07-03T15:05:17.385118", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.385339", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.385559", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-07-03T15:05:17.385780", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.386001", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.386221", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-07-03T15:05:17.386442", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.386662", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.386935", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-07-03T15:05:17.387198", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.387257", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-07-03T15:05:17.387684", "lvl": "target", "msg": "+ KIRK_WORKERS=1"}
- {"dt": "2025-07-03T15:05:17.387937", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n:w: arg"}
- {"dt": "2025-07-03T15:05:17.388952", "lvl": "target", "msg": "+ TEST_TARFILE=https://github.com/linux-test-project/ltp/releases/download//ltp-full-.tar.xz"}
- {"dt": "2025-07-03T15:05:17.389574", "lvl": "target", "msg": "+ '[' -n /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml ']'"}
- {"dt": "2025-07-03T15:05:17.389839", "lvl": "target", "msg": "+ export SKIPFILE_PATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile"}
- {"dt": "2025-07-03T15:05:17.390235", "lvl": "target", "msg": "+ SKIPFILE_PATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile"}
- {"dt": "2025-07-03T15:05:17.390301", "lvl": "target", "msg": "+ generate_skipfile"}
- {"dt": "2025-07-03T15:05:17.391090", "lvl": "target", "msg": "+ info_msg 'Generating a skipfile based on /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml'"}
- {"dt": "2025-07-03T15:05:17.391833", "lvl": "target", "msg": "+ local 'msg=Generating a skipfile based on /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml'"}
- {"dt": "2025-07-03T15:05:17.393033", "lvl": "target", "msg": "+ '[' -z 'Generating a skipfile based on /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml' ']'"}
- {"dt": "2025-07-03T15:05:17.393660", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Generating a skipfile based on /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml'"}
- {"dt": "2025-07-03T15:05:17.394340", "lvl": "target", "msg": "INFO: Generating a skipfile based on /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml"}
- {"dt": "2025-07-03T15:05:17.394976", "lvl": "target", "msg": "+ detect_abi"}
- {"dt": "2025-07-03T15:05:17.402899", "lvl": "target", "msg": "++ uname -m"}
- {"dt": "2025-07-03T15:05:17.417816", "lvl": "target", "msg": "+ abi=armv7l"}
- {"dt": "2025-07-03T15:05:17.418318", "lvl": "target", "msg": "+ case \"${abi}\" in"}
- {"dt": "2025-07-03T15:05:17.418395", "lvl": "target", "msg": "+ abi=armeabi"}
- {"dt": "2025-07-03T15:05:17.419072", "lvl": "target", "msg": "+ local SKIPGEN_ARGS="}
- {"dt": "2025-07-03T15:05:17.419300", "lvl": "target", "msg": "+ test -n ''"}
- {"dt": "2025-07-03T15:05:17.419519", "lvl": "target", "msg": "+ test -n ''"}
- {"dt": "2025-07-03T15:05:17.419745", "lvl": "target", "msg": "+ test -n production"}
- {"dt": "2025-07-03T15:05:17.420147", "lvl": "target", "msg": "+ SKIPGEN_ARGS=' --environment production'"}
- {"dt": "2025-07-03T15:05:17.420880", "lvl": "target", "msg": "+ ../../bin/armeabi/skipgen --environment production /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/skipfile-lkft.yaml"}
- {"dt": "2025-07-03T15:05:17.661017", "lvl": "target", "msg": "+ res=0"}
- {"dt": "2025-07-03T15:05:17.661380", "lvl": "target", "msg": "+ test 0 -eq 0"}
- {"dt": "2025-07-03T15:05:17.661644", "lvl": "target", "msg": "+ info_msg 'Using the following generated skipfile contents (until EOF):'"}
- {"dt": "2025-07-03T15:05:17.662122", "lvl": "target", "msg": "+ local 'msg=Using the following generated skipfile contents (until EOF):'"}
- {"dt": "2025-07-03T15:05:17.662592", "lvl": "target", "msg": "+ '[' -z 'Using the following generated skipfile contents (until EOF):' ']'"}
- {"dt": "2025-07-03T15:05:17.663006", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Using the following generated skipfile contents (until EOF):'"}
- {"dt": "2025-07-03T15:05:17.663288", "lvl": "target", "msg": "INFO: Using the following generated skipfile contents (until EOF):"}
- {"dt": "2025-07-03T15:05:17.663750", "lvl": "target", "msg": "+ cat /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile"}
- {"dt": "2025-07-03T15:05:17.677960", "lvl": "target", "msg": "bind06"}
- {"dt": "2025-07-03T15:05:17.678041", "lvl": "target", "msg": "cve-2018-18559"}
- {"dt": "2025-07-03T15:05:17.678109", "lvl": "target", "msg": "dio10"}
- {"dt": "2025-07-03T15:05:17.678170", "lvl": "target", "msg": "dio12"}
- {"dt": "2025-07-03T15:05:17.678233", "lvl": "target", "msg": "dio13"}
- {"dt": "2025-07-03T15:05:17.678307", "lvl": "target", "msg": "dio14"}
- {"dt": "2025-07-03T15:05:17.678384", "lvl": "target", "msg": "dio15"}
- {"dt": "2025-07-03T15:05:17.678445", "lvl": "target", "msg": "dio16"}
- {"dt": "2025-07-03T15:05:17.678506", "lvl": "target", "msg": "dio17"}
- {"dt": "2025-07-03T15:05:17.678565", "lvl": "target", "msg": "dio18"}
- {"dt": "2025-07-03T15:05:17.678610", "lvl": "target", "msg": "dio19"}
- {"dt": "2025-07-03T15:05:17.678650", "lvl": "target", "msg": "dio20"}
- {"dt": "2025-07-03T15:05:17.678685", "lvl": "target", "msg": "dio21"}
- {"dt": "2025-07-03T15:05:17.678742", "lvl": "target", "msg": "dio22"}
- {"dt": "2025-07-03T15:05:17.678777", "lvl": "target", "msg": "dio23"}
- {"dt": "2025-07-03T15:05:17.678817", "lvl": "target", "msg": "dio24"}
- {"dt": "2025-07-03T15:05:17.678851", "lvl": "target", "msg": "dio25"}
- {"dt": "2025-07-03T15:05:17.678891", "lvl": "target", "msg": "dio26"}
- {"dt": "2025-07-03T15:05:17.678930", "lvl": "target", "msg": "dio27"}
- {"dt": "2025-07-03T15:05:17.678962", "lvl": "target", "msg": "dio28"}
- {"dt": "2025-07-03T15:05:17.679002", "lvl": "target", "msg": "dio29"}
- {"dt": "2025-07-03T15:05:17.679040", "lvl": "target", "msg": "dio30"}
- {"dt": "2025-07-03T15:05:17.679086", "lvl": "target", "msg": "fanotify07"}
- {"dt": "2025-07-03T15:05:17.679130", "lvl": "target", "msg": "fanotify08"}
- {"dt": "2025-07-03T15:05:17.679170", "lvl": "target", "msg": "fanotify09"}
- {"dt": "2025-07-03T15:05:17.679204", "lvl": "target", "msg": "fork13"}
- {"dt": "2025-07-03T15:05:17.679243", "lvl": "target", "msg": "ftrace_stress_test.sh"}
- {"dt": "2025-07-03T15:05:17.679282", "lvl": "target", "msg": "futex_wake04"}
- {"dt": "2025-07-03T15:05:17.679315", "lvl": "target", "msg": "gf01"}
- {"dt": "2025-07-03T15:05:17.679354", "lvl": "target", "msg": "gf14"}
- {"dt": "2025-07-03T15:05:17.679394", "lvl": "target", "msg": "gf15"}
- {"dt": "2025-07-03T15:05:17.679427", "lvl": "target", "msg": "gf18"}
- {"dt": "2025-07-03T15:05:17.679466", "lvl": "target", "msg": "hackbench01"}
- {"dt": "2025-07-03T15:05:17.679505", "lvl": "target", "msg": "hackbench02"}
- {"dt": "2025-07-03T15:05:17.679538", "lvl": "target", "msg": "hugemmap06"}
- {"dt": "2025-07-03T15:05:17.679577", "lvl": "target", "msg": "inotify07"}
- {"dt": "2025-07-03T15:05:17.679617", "lvl": "target", "msg": "inotify08"}
- {"dt": "2025-07-03T15:05:17.679651", "lvl": "target", "msg": "ksm01"}
- {"dt": "2025-07-03T15:05:17.679690", "lvl": "target", "msg": "memcg_stress"}
- {"dt": "2025-07-03T15:05:17.679729", "lvl": "target", "msg": "msgctl10"}
- {"dt": "2025-07-03T15:05:17.679763", "lvl": "target", "msg": "msgctl11"}
- {"dt": "2025-07-03T15:05:17.679801", "lvl": "target", "msg": "msgstress03"}
- {"dt": "2025-07-03T15:05:17.679840", "lvl": "target", "msg": "msgstress04"}
- {"dt": "2025-07-03T15:05:17.679872", "lvl": "target", "msg": "mtest06"}
- {"dt": "2025-07-03T15:05:17.679924", "lvl": "target", "msg": "perf_event_open02"}
- {"dt": "2025-07-03T15:05:17.679965", "lvl": "target", "msg": "ping01"}
- {"dt": "2025-07-03T15:05:17.680001", "lvl": "target", "msg": "ping602"}
- {"dt": "2025-07-03T15:05:17.680041", "lvl": "target", "msg": "pth_str01"}
- {"dt": "2025-07-03T15:05:17.680074", "lvl": "target", "msg": "pth_str02"}
- {"dt": "2025-07-03T15:05:17.680112", "lvl": "target", "msg": "pth_str03"}
- {"dt": "2025-07-03T15:05:17.680150", "lvl": "target", "msg": "sendfile09"}
- {"dt": "2025-07-03T15:05:17.680183", "lvl": "target", "msg": "sendfile09_64"}
- {"dt": "2025-07-03T15:05:17.680221", "lvl": "target", "msg": "setsockopt06"}
- {"dt": "2025-07-03T15:05:17.680259", "lvl": "target", "msg": "time-schedule01"}
- {"dt": "2025-07-03T15:05:17.681619", "lvl": "target", "msg": "+ info_msg EOF"}
- {"dt": "2025-07-03T15:05:17.682205", "lvl": "target", "msg": "+ local msg=EOF"}
- {"dt": "2025-07-03T15:05:17.682455", "lvl": "target", "msg": "+ '[' -z EOF ']'"}
- {"dt": "2025-07-03T15:05:17.682699", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' EOF"}
- {"dt": "2025-07-03T15:05:17.688113", "lvl": "target", "msg": "INFO: EOF"}
- {"dt": "2025-07-03T15:05:17.688807", "lvl": "target", "msg": "+ '[' '!' -f /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile ']'"}
- {"dt": "2025-07-03T15:05:17.689392", "lvl": "target", "msg": "+ SKIPFILE='-S /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile'"}
- {"dt": "2025-07-03T15:05:17.693656", "lvl": "target", "msg": "+ check_root"}
- {"dt": "2025-07-03T15:05:17.723238", "lvl": "target", "msg": "++ id -ru"}
- {"dt": "2025-07-03T15:05:17.820827", "lvl": "target", "msg": "+ '[' 0 -eq 0 ']'"}
- {"dt": "2025-07-03T15:05:17.821163", "lvl": "target", "msg": "+ return 0"}
- {"dt": "2025-07-03T15:05:17.822110", "lvl": "target", "msg": "+ create_out_dir /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-07-03T15:05:17.822603", "lvl": "target", "msg": "+ '[' -z /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-07-03T15:05:17.823126", "lvl": "target", "msg": "+ local OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-07-03T15:05:17.823584", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-07-03T15:05:17.824006", "lvl": "target", "msg": "+ mkdir -p /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-07-03T15:05:17.857241", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-07-03T15:05:17.858517", "lvl": "target", "msg": "+ info_msg 'About to run ltp test...'"}
- {"dt": "2025-07-03T15:05:17.858939", "lvl": "target", "msg": "+ local 'msg=About to run ltp test...'"}
- {"dt": "2025-07-03T15:05:17.859200", "lvl": "target", "msg": "+ '[' -z 'About to run ltp test...' ']'"}
- {"dt": "2025-07-03T15:05:17.859647", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'About to run ltp test...'"}
- {"dt": "2025-07-03T15:05:17.859724", "lvl": "target", "msg": "INFO: About to run ltp test..."}
- {"dt": "2025-07-03T15:05:17.860220", "lvl": "target", "msg": "+ info_msg 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-07-03T15:05:17.860628", "lvl": "target", "msg": "+ local 'msg=Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-07-03T15:05:17.861041", "lvl": "target", "msg": "+ '[' -z 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output' ']'"}
- {"dt": "2025-07-03T15:05:17.861482", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-07-03T15:05:17.861823", "lvl": "target", "msg": "INFO: Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-07-03T15:05:17.862051", "lvl": "target", "msg": "+ '[' true = true ']'"}
- {"dt": "2025-07-03T15:05:17.862450", "lvl": "target", "msg": "+ info_msg 'ltp installation skipped altogether'"}
- {"dt": "2025-07-03T15:05:17.862669", "lvl": "target", "msg": "+ local 'msg=ltp installation skipped altogether'"}
- {"dt": "2025-07-03T15:05:17.863170", "lvl": "target", "msg": "+ '[' -z 'ltp installation skipped altogether' ']'"}
- {"dt": "2025-07-03T15:05:17.863435", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'ltp installation skipped altogether'"}
- {"dt": "2025-07-03T15:05:17.863694", "lvl": "target", "msg": "INFO: ltp installation skipped altogether"}
- {"dt": "2025-07-03T15:05:17.864163", "lvl": "target", "msg": "+ '[' '!' -d /opt/ltp/ ']'"}
- {"dt": "2025-07-03T15:05:17.868147", "lvl": "target", "msg": "+ info_msg 'Running prep_system'"}
- {"dt": "2025-07-03T15:05:17.868484", "lvl": "target", "msg": "+ local 'msg=Running prep_system'"}
- {"dt": "2025-07-03T15:05:17.868767", "lvl": "target", "msg": "+ '[' -z 'Running prep_system' ']'"}
- {"dt": "2025-07-03T15:05:17.869043", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running prep_system'"}
- {"dt": "2025-07-03T15:05:17.869094", "lvl": "target", "msg": "INFO: Running prep_system"}
- {"dt": "2025-07-03T15:05:17.869309", "lvl": "target", "msg": "+ prep_system"}
- {"dt": "2025-07-03T15:05:17.869725", "lvl": "target", "msg": "+ systemctl is-active systemd-timesyncd"}
- {"dt": "2025-07-03T15:05:18.032261", "lvl": "target", "msg": "inactive"}
- {"dt": "2025-07-03T15:05:18.039307", "lvl": "target", "msg": "+ '[' -f /proc/sys/kernel/unprivileged_userns_clone ']'"}
- {"dt": "2025-07-03T15:05:18.039626", "lvl": "target", "msg": "+ info_msg 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-07-03T15:05:18.040292", "lvl": "target", "msg": "+ local 'msg=Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-07-03T15:05:18.040765", "lvl": "target", "msg": "+ '[' -z 'Kernel has no support of unprivileged_userns_clone' ']'"}
- {"dt": "2025-07-03T15:05:18.041113", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-07-03T15:05:18.041177", "lvl": "target", "msg": "INFO: Kernel has no support of unprivileged_userns_clone"}
- {"dt": "2025-07-03T15:05:18.041882", "lvl": "target", "msg": "+ info_msg 'Running run_ltp'"}
- {"dt": "2025-07-03T15:05:18.042103", "lvl": "target", "msg": "+ local 'msg=Running run_ltp'"}
- {"dt": "2025-07-03T15:05:18.042502", "lvl": "target", "msg": "+ '[' -z 'Running run_ltp' ']'"}
- {"dt": "2025-07-03T15:05:18.042719", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running run_ltp'"}
- {"dt": "2025-07-03T15:05:18.042781", "lvl": "target", "msg": "INFO: Running run_ltp"}
- {"dt": "2025-07-03T15:05:18.043000", "lvl": "target", "msg": "+ run_ltp"}
- {"dt": "2025-07-03T15:05:18.043524", "lvl": "target", "msg": "+ cd /opt/ltp/"}
- {"dt": "2025-07-03T15:05:18.044241", "lvl": "target", "msg": "+ mkdir -m 777 -p /scratch"}
- {"dt": "2025-07-03T15:05:18.074265", "lvl": "target", "msg": "+ for file in ${TST_CMDFILES//,/ }"}
- {"dt": "2025-07-03T15:05:18.074401", "lvl": "target", "msg": "+ cat runtest/smoketest"}
- {"dt": "2025-07-03T15:05:18.114296", "lvl": "target", "msg": "+ sed -i 's/#.*$//;/^$/d' alltests"}
- {"dt": "2025-07-03T15:05:18.150879", "lvl": "target", "msg": "+ split --verbose --numeric-suffixes=1 -n l/1/1 alltests"}
- {"dt": "2025-07-03T15:05:18.177224", "lvl": "target", "msg": "+ echo '============== Tests to run ==============='"}
- {"dt": "2025-07-03T15:05:18.177534", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-07-03T15:05:18.178196", "lvl": "target", "msg": "+ cat runtest/shardfile"}
- {"dt": "2025-07-03T15:05:18.190531", "lvl": "target", "msg": "access01 access01"}
- {"dt": "2025-07-03T15:05:18.190635", "lvl": "target", "msg": "chdir01 chdir01"}
- {"dt": "2025-07-03T15:05:18.190738", "lvl": "target", "msg": "fork01 fork01"}
- {"dt": "2025-07-03T15:05:18.190799", "lvl": "target", "msg": "time01 time01"}
- {"dt": "2025-07-03T15:05:18.190854", "lvl": "target", "msg": "wait02 wait02"}
- {"dt": "2025-07-03T15:05:18.190937", "lvl": "target", "msg": "write01 write01"}
- {"dt": "2025-07-03T15:05:18.191007", "lvl": "target", "msg": "stat04 stat04"}
- {"dt": "2025-07-03T15:05:18.191071", "lvl": "target", "msg": "splice02 splice02 -s 20"}
- {"dt": "2025-07-03T15:05:18.191127", "lvl": "target", "msg": "df01_sh df01.sh"}
- {"dt": "2025-07-03T15:05:18.191182", "lvl": "target", "msg": "shell_test01 echo \"SUCCESS\" | shell_pipe01.sh"}
- {"dt": "2025-07-03T15:05:18.191237", "lvl": "target", "msg": "ping602 ping02.sh -6"}
- {"dt": "2025-07-03T15:05:18.191304", "lvl": "target", "msg": "macsec02 macsec02.sh"}
- {"dt": "2025-07-03T15:05:18.199080", "lvl": "target", "msg": "+ echo '===========End Tests to run ==============='"}
- {"dt": "2025-07-03T15:05:18.199417", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-07-03T15:05:18.199666", "lvl": "target", "msg": "+ '[' -n kirk ']'"}
- {"dt": "2025-07-03T15:05:18.199899", "lvl": "target", "msg": "+ eval kirk --version"}
- {"dt": "2025-07-03T15:05:18.200599", "lvl": "target", "msg": "++ kirk --version"}
- {"dt": "2025-07-03T15:05:20.415702", "lvl": "target", "msg": "kirk, 2.1"}
- {"dt": "2025-07-03T15:05:20.492356", "lvl": "target", "msg": "+ '[' 0 -ne 0 ']'"}
- {"dt": "2025-07-03T15:05:20.492716", "lvl": "target", "msg": "+ '[' 1 = max ']'"}
- {"dt": "2025-07-03T15:05:20.494820", "lvl": "target", "msg": "+ pipe0_status 'kirk --framework ltp --run-suite shardfile                                 -d /scratch --env LTP_COLORIZE_OUTPUT=0                                 --skip-file /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile                                 --workers 1 \t\t\t\t--no-colors                                 --json-report /tmp/kirk-report.json' 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-07-03T15:05:20.495299", "lvl": "target", "msg": "+ '[' 2 -ne 2 ']'"}
- {"dt": "2025-07-03T15:05:20.496376", "lvl": "target", "msg": "+ local 'cmd1=kirk --framework ltp --run-suite shardfile                                 -d /scratch --env LTP_COLORIZE_OUTPUT=0                                 --skip-file /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile                                 --workers 1 \t\t\t\t--no-colors                                 --json-report /tmp/kirk-report.json'"}
- {"dt": "2025-07-03T15:05:20.496738", "lvl": "target", "msg": "+ local 'cmd2=tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-07-03T15:05:20.496790", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-07-03T15:05:20.497288", "lvl": "target", "msg": "+ local ret_val"}
- {"dt": "2025-07-03T15:05:20.511803", "lvl": "target", "msg": "++ eval 'kirk --framework ltp --run-suite shardfile                                 -d /scratch --env LTP_COLORIZE_OUTPUT=0                                 --skip-file /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile                                 --workers 1 \t\t\t\t--no-colors                                 --json-report /tmp/kirk-report.json'"}
- {"dt": "2025-07-03T15:05:20.512966", "lvl": "target", "msg": "++ eval 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-07-03T15:05:20.513611", "lvl": "target", "msg": "+++ kirk --framework ltp --run-suite shardfile -d /scratch --env LTP_COLORIZE_OUTPUT=0 --skip-file /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/generated_skipfile --workers 1 --no-colors --json-report /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:05:20.514871", "lvl": "target", "msg": "+++ tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out"}
- {"dt": "2025-07-03T15:05:21.554562", "lvl": "target", "msg": "Host information"}
- {"dt": "2025-07-03T15:05:21.554750", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:21.554856", "lvl": "target", "msg": "\tHostname:   runner-ns46nmmj-project-40964107-concurrent-0"}
- {"dt": "2025-07-03T15:05:21.554933", "lvl": "target", "msg": "\tPython:     3.13.3 (main, Apr 10 2025, 21:38:51) [GCC 14.2.0]"}
- {"dt": "2025-07-03T15:05:21.554988", "lvl": "target", "msg": "\tDirectory:  /scratch/kirk.root/tmpymgz5fke"}
- {"dt": "2025-07-03T15:05:21.555038", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:21.567000", "lvl": "target", "msg": "Connecting to SUT: host"}
- {"dt": "2025-07-03T15:05:21.806489", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:05:21.806858", "lvl": "target", "msg": "Starting suite: shardfile"}
- {"dt": "2025-07-03T15:05:21.806945", "lvl": "target", "msg": "--------------------------"}
- {"dt": "2025-07-03T15:05:21.874237", "lvl": "target", "msg": "access01: <12>[   31.143804] /usr/local/bin/kirk[318]: access01: start (command: access01)"}
- {"dt": "2025-07-03T15:05:22.596845", "lvl": "target", "msg": "pass  (0.627s)"}
- {"dt": "2025-07-03T15:05:22.616013", "lvl": "target", "msg": "<12>[   31.885688] /usr/local/bin/kirk[318]: access01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:22.665667", "lvl": "target", "msg": "chdir01: <12>[   31.935230] /usr/local/bin/kirk[318]: chdir01: start (command: chdir01)"}
- {"dt": "2025-07-03T15:05:23.219457", "lvl": "target", "msg": "<6>[   32.488917] loop0: detected capacity change from 0 to 614400"}
- {"dt": "2025-07-03T15:05:23.334130", "lvl": "target", "msg": "<3>[   32.603511] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:23.388759", "lvl": "target", "msg": "<3>[   32.658465] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:23.415722", "lvl": "target", "msg": "<3>[   32.685437] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:23.537318", "lvl": "target", "msg": "<3>[   32.807002] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:23.597416", "lvl": "target", "msg": "<3>[   32.866358] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:23.986327", "lvl": "target", "msg": "<3>[   33.255984] operation not supported error, dev loop0, sector 614272 op 0x9:(WRITE_ZEROES) flags 0x10000800 phys_seg 0 prio class 0"}
- {"dt": "2025-07-03T15:05:24.182330", "lvl": "target", "msg": "<6>[   33.452044] EXT4-fs (loop0): mounting ext2 file system using the ext4 subsystem"}
- {"dt": "2025-07-03T15:05:24.191448", "lvl": "target", "msg": "<6>[   33.461030] EXT4-fs (loop0): mounted filesystem f2f14e25-d4de-444f-a7a4-7532f1d55509 r/w without journal. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:24.239320", "lvl": "target", "msg": "<6>[   33.508364] EXT4-fs (loop0): unmounting filesystem f2f14e25-d4de-444f-a7a4-7532f1d55509."}
- {"dt": "2025-07-03T15:05:25.675519", "lvl": "target", "msg": "<6>[   34.945191] EXT4-fs (loop0): mounting ext3 file system using the ext4 subsystem"}
- {"dt": "2025-07-03T15:05:25.684339", "lvl": "target", "msg": "<6>[   34.953820] EXT4-fs (loop0): mounted filesystem cfc27fad-f173-456a-b952-33e9b25e77f7 r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:25.717015", "lvl": "target", "msg": "<6>[   34.986733] EXT4-fs (loop0): unmounting filesystem cfc27fad-f173-456a-b952-33e9b25e77f7."}
- {"dt": "2025-07-03T15:05:25.945831", "lvl": "target", "msg": "<6>[   35.215433] EXT4-fs (loop0): mounted filesystem 13bd2f61-6057-4c04-a579-53ba6dc1f4bf r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:25.991808", "lvl": "target", "msg": "<6>[   35.260341] EXT4-fs (loop0): unmounting filesystem 13bd2f61-6057-4c04-a579-53ba6dc1f4bf."}
- {"dt": "2025-07-03T15:05:36.032145", "lvl": "target", "msg": "skip  (13.278s)"}
- {"dt": "2025-07-03T15:05:36.060466", "lvl": "target", "msg": "<12>[   45.330204] /usr/local/bin/kirk[318]: chdir01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:36.110538", "lvl": "target", "msg": "fork01: <12>[   45.380067] /usr/local/bin/kirk[318]: fork01: start (command: fork01)"}
- {"dt": "2025-07-03T15:05:36.290955", "lvl": "target", "msg": "pass  (0.084s)"}
- {"dt": "2025-07-03T15:05:36.315291", "lvl": "target", "msg": "<12>[   45.584917] /usr/local/bin/kirk[318]: fork01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:36.365528", "lvl": "target", "msg": "time01: <12>[   45.635142] /usr/local/bin/kirk[318]: time01: start (command: time01)"}
- {"dt": "2025-07-03T15:05:36.536224", "lvl": "target", "msg": "pass  (0.081s)"}
- {"dt": "2025-07-03T15:05:36.558805", "lvl": "target", "msg": "<12>[   45.828454] /usr/local/bin/kirk[318]: time01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:36.605191", "lvl": "target", "msg": "wait02: <12>[   45.874884] /usr/local/bin/kirk[318]: wait02: start (command: wait02)"}
- {"dt": "2025-07-03T15:05:36.766617", "lvl": "target", "msg": "pass  (0.074s)"}
- {"dt": "2025-07-03T15:05:36.788900", "lvl": "target", "msg": "<12>[   46.058500] /usr/local/bin/kirk[318]: wait02: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:36.836674", "lvl": "target", "msg": "write01: <12>[   46.106438] /usr/local/bin/kirk[318]: write01: start (command: write01)"}
- {"dt": "2025-07-03T15:05:37.670886", "lvl": "target", "msg": "pass  (0.743s)"}
- {"dt": "2025-07-03T15:05:37.696488", "lvl": "target", "msg": "<12>[   46.966116] /usr/local/bin/kirk[318]: write01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:37.751012", "lvl": "target", "msg": "stat04: <12>[   47.020682] /usr/local/bin/kirk[318]: stat04: start (command: stat04)"}
- {"dt": "2025-07-03T15:05:38.295176", "lvl": "target", "msg": "<6>[   47.564647] loop0: detected capacity change from 0 to 614400"}
- {"dt": "2025-07-03T15:05:38.732088", "lvl": "target", "msg": "<3>[   48.001749] operation not supported error, dev loop0, sector 614272 op 0x9:(WRITE_ZEROES) flags 0x10000800 phys_seg 0 prio class 0"}
- {"dt": "2025-07-03T15:05:38.918127", "lvl": "target", "msg": "<6>[   48.187756] EXT4-fs (loop0): mounting ext2 file system using the ext4 subsystem"}
- {"dt": "2025-07-03T15:05:38.933118", "lvl": "target", "msg": "<6>[   48.202689] EXT4-fs (loop0): mounted filesystem 9f66f966-6657-435b-a4eb-3428ef582c78 r/w without journal. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:40.003711", "lvl": "target", "msg": "<6>[   49.273410] EXT4-fs (loop0): unmounting filesystem 9f66f966-6657-435b-a4eb-3428ef582c78."}
- {"dt": "2025-07-03T15:05:40.159333", "lvl": "target", "msg": "pass  (2.295s)"}
- {"dt": "2025-07-03T15:05:40.181166", "lvl": "target", "msg": "<12>[   49.450642] /usr/local/bin/kirk[318]: stat04: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:40.233100", "lvl": "target", "msg": "splice02: <12>[   49.502858] /usr/local/bin/kirk[318]: splice02: start (command: splice02 -s 20)"}
- {"dt": "2025-07-03T15:05:40.405083", "lvl": "target", "msg": "pass  (0.085s)"}
- {"dt": "2025-07-03T15:05:40.431268", "lvl": "target", "msg": "<12>[   49.700905] /usr/local/bin/kirk[318]: splice02: end (returncode: 0)"}
- {"dt": "2025-07-03T15:05:40.481448", "lvl": "target", "msg": "df01_sh: <12>[   49.751089] /usr/local/bin/kirk[318]: df01_sh: start (command: df01.sh)"}
- {"dt": "2025-07-03T15:05:41.229566", "lvl": "target", "msg": "<6>[   50.499189] loop0: detected capacity change from 0 to 614400"}
- {"dt": "2025-07-03T15:05:41.359009", "lvl": "target", "msg": "<3>[   50.628619] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:41.421392", "lvl": "target", "msg": "<3>[   50.690963] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:41.446733", "lvl": "target", "msg": "<3>[   50.716501] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:41.560814", "lvl": "target", "msg": "<3>[   50.830559] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:41.611943", "lvl": "target", "msg": "<3>[   50.881581] /dev/zero: Can't lookup blockdev"}
- {"dt": "2025-07-03T15:05:42.013825", "lvl": "target", "msg": "<3>[   51.283221] operation not supported error, dev loop0, sector 614272 op 0x9:(WRITE_ZEROES) flags 0x10000800 phys_seg 0 prio class 0"}
- {"dt": "2025-07-03T15:05:42.261961", "lvl": "target", "msg": "<6>[   51.531709] EXT4-fs (loop0): mounting ext2 file system using the ext4 subsystem"}
- {"dt": "2025-07-03T15:05:42.267813", "lvl": "target", "msg": "<6>[   51.534209] EXT4-fs (loop0): mounted filesystem 6e035b10-3469-49d1-acdc-e708bdfaf389 r/w without journal. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:45.091659", "lvl": "target", "msg": "<6>[   54.361180] EXT4-fs (loop0): unmounting filesystem 6e035b10-3469-49d1-acdc-e708bdfaf389."}
- {"dt": "2025-07-03T15:05:46.577276", "lvl": "target", "msg": "<6>[   55.846832] EXT4-fs (loop0): mounting ext3 file system using the ext4 subsystem"}
- {"dt": "2025-07-03T15:05:46.587908", "lvl": "target", "msg": "<6>[   55.857205] EXT4-fs (loop0): mounted filesystem 19ca8d83-4d23-4e54-b43f-8057606fa65b r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:49.386177", "lvl": "target", "msg": "<6>[   58.655770] EXT4-fs (loop0): unmounting filesystem 19ca8d83-4d23-4e54-b43f-8057606fa65b."}
- {"dt": "2025-07-03T15:05:49.698827", "lvl": "target", "msg": "<6>[   58.968477] EXT4-fs (loop0): mounted filesystem a7c370c2-4ea1-44c6-a4e5-dc2450329c9b r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-03T15:05:51.999394", "lvl": "target", "msg": "<6>[   61.268344] EXT4-fs (loop0): unmounting filesystem a7c370c2-4ea1-44c6-a4e5-dc2450329c9b."}
- {"dt": "2025-07-03T15:06:17.073578", "lvl": "target", "msg": "pass  (36.501s)"}
- {"dt": "2025-07-03T15:06:17.094086", "lvl": "target", "msg": "<12>[   86.363831] /usr/local/bin/kirk[318]: df01_sh: end (returncode: 0)"}
- {"dt": "2025-07-03T15:06:17.144808", "lvl": "target", "msg": "shell_test01: <12>[   86.414563] /usr/local/bin/kirk[318]: shell_test01: start (command: echo SUCCESS | shell_pipe01.sh)"}
- {"dt": "2025-07-03T15:06:17.466375", "lvl": "target", "msg": "pass  (0.230s)"}
- {"dt": "2025-07-03T15:06:17.487735", "lvl": "target", "msg": "<12>[   86.757284] /usr/local/bin/kirk[318]: shell_test01: end (returncode: 0)"}
- {"dt": "2025-07-03T15:06:17.538719", "lvl": "target", "msg": "macsec02: <12>[   86.808455] /usr/local/bin/kirk[318]: macsec02: start (command: macsec02.sh)"}
- {"dt": "2025-07-03T15:06:20.690662", "lvl": "target", "msg": "skip  (3.065s)"}
- {"dt": "2025-07-03T15:06:20.716633", "lvl": "target", "msg": "<12>[   89.985981] /usr/local/bin/kirk[318]: macsec02: end (returncode: 32)"}
- {"dt": "2025-07-03T15:06:20.747107", "lvl": "target", "msg": "                                                                                                                                "}
- {"dt": "2025-07-03T15:06:20.747245", "lvl": "target", "msg": "Execution time: 58.893s"}
- {"dt": "2025-07-03T15:06:20.747297", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:06:20.747351", "lvl": "target", "msg": "\tSuite:       shardfile"}
- {"dt": "2025-07-03T15:06:20.747397", "lvl": "target", "msg": "\tTotal runs:  11"}
- {"dt": "2025-07-03T15:06:20.747440", "lvl": "target", "msg": "\tRuntime:     57.064s"}
- {"dt": "2025-07-03T15:06:20.747486", "lvl": "target", "msg": "\tPassed:      380"}
- {"dt": "2025-07-03T15:06:20.747541", "lvl": "target", "msg": "\tFailed:      0"}
- {"dt": "2025-07-03T15:06:20.747767", "lvl": "target", "msg": "\tSkipped:     4"}
- {"dt": "2025-07-03T15:06:20.747812", "lvl": "target", "msg": "\tBroken:      0"}
- {"dt": "2025-07-03T15:06:20.747854", "lvl": "target", "msg": "\tWarnings:    0"}
- {"dt": "2025-07-03T15:06:20.747904", "lvl": "target", "msg": "\tKernel:      Linux 6.16.0-rc4-00003-g1135b1f1b3f6 #1 SMP @1751276979"}
- {"dt": "2025-07-03T15:06:20.748130", "lvl": "target", "msg": "\tMachine:     unknown"}
- {"dt": "2025-07-03T15:06:20.748172", "lvl": "target", "msg": "\tArch:        armv7l"}
- {"dt": "2025-07-03T15:06:20.748213", "lvl": "target", "msg": "\tRAM:         4053308 kB"}
- {"dt": "2025-07-03T15:06:20.748257", "lvl": "target", "msg": "\tSwap:        0 kB"}
- {"dt": "2025-07-03T15:06:20.748308", "lvl": "target", "msg": "\tDistro:      debian 13"}
- {"dt": "2025-07-03T15:06:20.763776", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:06:20.764037", "lvl": "target", "msg": "Disconnecting from SUT: host"}
- {"dt": "2025-07-03T15:06:20.774724", "lvl": "target", "msg": "Session stopped"}
- {"dt": "2025-07-03T15:06:20.856060", "lvl": "target", "msg": "++ echo 0"}
- {"dt": "2025-07-03T15:06:20.866727", "lvl": "target", "msg": "+ ret_val=0"}
- {"dt": "2025-07-03T15:06:20.866841", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-07-03T15:06:20.867435", "lvl": "target", "msg": "+ return 0"}
- {"dt": "2025-07-03T15:06:20.868305", "lvl": "target", "msg": "+ parse_ltp_json_results /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:06:20.874686", "lvl": "target", "msg": "+ jq -r '.results| .[]| \"\\(.test_fqn) \\(.test.result)\"' /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:06:20.878926", "lvl": "target", "msg": "+ sed 's/brok/fail/; s/conf/skip/'"}
- {"dt": "2025-07-03T15:06:20.956335", "lvl": "target", "msg": "++ jq -r '.results| .[]| select(.test.result != \"pass\") | .test_fqn' /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:06:21.005397", "lvl": "target", "msg": "+ for test_fqn in $(jq -r '.results| .[]| select(.test.result != \"pass\") | .test_fqn' \"$1\")"}
- {"dt": "2025-07-03T15:06:21.006347", "lvl": "target", "msg": "+ jq -r '.results | .[] | select(.test_fqn == \"macsec02\") | .test.log' /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:06:21.056384", "lvl": "target", "msg": "+ rm /tmp/kirk-report.json"}
- {"dt": "2025-07-03T15:06:21.071180", "lvl": "target", "msg": "+ rm -rf /scratch"}
- {"dt": "2025-07-03T15:06:21.088902", "lvl": "target", "msg": "+ rm -rf alltests"}
- {"dt": "2025-07-03T15:06:21.105348", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-07-03T15:06:21.169898", "lvl": "target", "msg": "<8>[   90.439535] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=access01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.170228", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=access01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.170302", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "access01", "result": "pass", "starttc": ! "1087", "endtc": ! "1087"}}
- {"dt": "2025-07-03T15:06:21.210532", "lvl": "target", "msg": "<8>[   90.479623] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=chdir01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.210852", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=chdir01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.210986", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "chdir01", "result": "pass", "starttc": ! "1090", "endtc": ! "1090"}}
- {"dt": "2025-07-03T15:06:21.252058", "lvl": "target", "msg": "<8>[   90.521793] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=fork01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.252378", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=fork01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.252487", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "fork01", "result": "pass", "starttc": ! "1093", "endtc": ! "1093"}}
- {"dt": "2025-07-03T15:06:21.294433", "lvl": "target", "msg": "<8>[   90.564165] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=time01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.294722", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=time01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.294816", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "time01", "result": "pass", "starttc": ! "1096", "endtc": ! "1096"}}
- {"dt": "2025-07-03T15:06:21.335359", "lvl": "target", "msg": "<8>[   90.605094] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=wait02 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.335653", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=wait02 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.335762", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "wait02", "result": "pass", "starttc": ! "1099", "endtc": ! "1099"}}
- {"dt": "2025-07-03T15:06:21.378550", "lvl": "target", "msg": "<8>[   90.648318] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=write01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.378849", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=write01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.378951", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "write01", "result": "pass", "starttc": ! "1102", "endtc": ! "1102"}}
- {"dt": "2025-07-03T15:06:21.418598", "lvl": "target", "msg": "<8>[   90.688379] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=stat04 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.418910", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=stat04 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.419003", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "stat04", "result": "pass", "starttc": ! "1105", "endtc": ! "1105"}}
- {"dt": "2025-07-03T15:06:21.458880", "lvl": "target", "msg": "<8>[   90.728621] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=splice02 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.459184", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=splice02 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.459295", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "splice02", "result": "pass", "starttc": ! "1108", "endtc": ! "1108"}}
- {"dt": "2025-07-03T15:06:21.499001", "lvl": "target", "msg": "<8>[   90.768747] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=df01_sh RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.499263", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=df01_sh RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.499324", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "df01_sh", "result": "pass", "starttc": ! "1111", "endtc": ! "1111"}}
- {"dt": "2025-07-03T15:06:21.538886", "lvl": "target", "msg": "<8>[   90.808596] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shell_test01 RESULT=pass>"}
- {"dt": "2025-07-03T15:06:21.539198", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shell_test01 RESULT=pass"}
- {"dt": "2025-07-03T15:06:21.539291", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "shell_test01", "result": "pass", "starttc": ! "1114", "endtc": ! "1114"}}
- {"dt": "2025-07-03T15:06:21.558320", "lvl": "target", "msg": "<8>[   90.827383] <LAVA_SIGNAL_STARTTC macsec02>"}
- {"dt": "2025-07-03T15:06:21.558601", "lvl": "debug", "msg": "Received signal: <STARTTC> macsec02"}
- {"dt": "2025-07-03T15:06:21.565099", "lvl": "target", "msg": "macsec02 1 TINFO: Running: macsec02.sh "}
- {"dt": "2025-07-03T15:06:21.565467", "lvl": "target", "msg": "macsec02 1 TINFO: Tested kernel: Linux runner-ns46nmmj-project-40964107-concurrent-0 6.16.0-rc4-00003-g1135b1f1b3f6 #1 SMP @1751276979 armv7l GNU/Linux"}
- {"dt": "2025-07-03T15:06:21.565549", "lvl": "target", "msg": "macsec02 1 TINFO: initialize 'lhost' 'ltp_ns_veth2' interface"}
- {"dt": "2025-07-03T15:06:21.565619", "lvl": "target", "msg": "macsec02 1 TINFO: add local addr 10.0.0.2/24"}
- {"dt": "2025-07-03T15:06:21.565695", "lvl": "target", "msg": "macsec02 1 TINFO: add local addr fd00:1:1:1::2/64"}
- {"dt": "2025-07-03T15:06:21.565950", "lvl": "target", "msg": "macsec02 1 TINFO: initialize 'rhost' 'ltp_ns_veth1' interface"}
- {"dt": "2025-07-03T15:06:21.565992", "lvl": "target", "msg": "macsec02 1 TINFO: add remote addr 10.0.0.1/24"}
- {"dt": "2025-07-03T15:06:21.566036", "lvl": "target", "msg": "macsec02 1 TINFO: add remote addr fd00:1:1:1::1/64"}
- {"dt": "2025-07-03T15:06:21.566271", "lvl": "target", "msg": "macsec02 1 TINFO: Network config (local -- remote):"}
- {"dt": "2025-07-03T15:06:21.566312", "lvl": "target", "msg": "macsec02 1 TINFO: ltp_ns_veth2 -- ltp_ns_veth1"}
- {"dt": "2025-07-03T15:06:21.566354", "lvl": "target", "msg": "macsec02 1 TINFO: 10.0.0.2/24 -- 10.0.0.1/24"}
- {"dt": "2025-07-03T15:06:21.566592", "lvl": "target", "msg": "macsec02 1 TINFO: fd00:1:1:1::2/64 -- fd00:1:1:1::1/64"}
- {"dt": "2025-07-03T15:06:21.566631", "lvl": "target", "msg": "macsec02 1 TCONF: macsec driver not available"}
- {"dt": "2025-07-03T15:06:21.566665", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:06:21.566706", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-07-03T15:06:21.566747", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-07-03T15:06:21.566782", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-07-03T15:06:21.566814", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-07-03T15:06:21.566845", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-07-03T15:06:21.566875", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-07-03T15:06:21.566913", "lvl": "target", "msg": ""}
- {"dt": "2025-07-03T15:06:21.568701", "lvl": "target", "msg": "<8>[   90.838556] <LAVA_SIGNAL_ENDTC macsec02>"}
- {"dt": "2025-07-03T15:06:21.568921", "lvl": "debug", "msg": "Received signal: <ENDTC> macsec02"}
- {"dt": "2025-07-03T15:06:21.592638", "lvl": "target", "msg": "<8>[   90.862318] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=macsec02 RESULT=skip>"}
- {"dt": "2025-07-03T15:06:21.592915", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=macsec02 RESULT=skip"}
- {"dt": "2025-07-03T15:06:21.593002", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "macsec02", "result": "skip", "starttc": ! "1117", "endtc": ! "1140"}}
- {"dt": "2025-07-03T15:06:21.595256", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-07-03T15:06:21.596466", "lvl": "target", "msg": "<8>[   90.865580] <LAVA_SIGNAL_ENDRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-07-03T15:06:21.596682", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-07-03T15:06:21.596746", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-07-03T15:06:21.596788", "lvl": "info", "msg": "Ending test lava.0_ltp-smoke (1_1.1.3.1), duration 64.45"}
- {"dt": "2025-07-03T15:06:21.596849", "lvl": "results", "msg": {"definition": "lava", "case": "0_ltp-smoke", "uuid": "1_1.1.3.1", "repository": "https://github.com/broonie/test-definitions.git", "path": "automated/linux/ltp/ltp.yaml", "duration": "64.45", "result": "pass", "revision": "unspecified", "namespace": "common", "commit_id": "7150958affedbc2fde3f9f8aab5b1a7bf5703c9e"}}
- {"dt": "2025-07-03T15:06:21.600133", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-07-03T15:06:21.600371", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-07-03T15:06:21.600505", "lvl": "debug", "msg": "access01: pass\nchdir01: pass\nfork01: pass\ntime01: pass\nwait02: pass\nwrite01: pass\nstat04: pass\nsplice02: pass\ndf01_sh: pass\nshell_test01: pass\nmacsec02: skip\n"}
- {"dt": "2025-07-03T15:06:21.600597", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:01:05) [common]"}
- {"dt": "2025-07-03T15:06:21.600678", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:01:05) [common]"}
- {"dt": "2025-07-03T15:06:21.600761", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:06:21.600842", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-07-03T15:06:21.600897", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-07-03T15:06:21.600949", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-07-03T15:06:21.601048", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-03T15:06:21.603876", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-03T15:06:21.604131", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-03T15:06:21.604416", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-03T15:06:21.604665", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-03T15:06:22.602362", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-07-03T15:06:22.602532", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-07-03T15:06:22.702990", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-07-03T15:06:22.703448", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-07-03T15:06:22.703537", "lvl": "target", "msg": "[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# poweroff"}
- {"dt": "2025-07-03T15:06:22.803843", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-07-03T15:06:22.803998", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-07-03T15:06:22.804089", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-07-03T15:06:22.804206", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-07-03T15:06:22.804335", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/kernel/zImage"}
- {"dt": "2025-07-03T15:06:22.806559", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs/https___storage.tuxboot.com_debian_20250617_trixie_armhf_rootfs.ext4"}
- {"dt": "2025-07-03T15:06:22.946850", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.modules/modules.tar"}
- {"dt": "2025-07-03T15:06:22.951839", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1/deployimages-wzq0k3ed/rootfs.overlay-00/ltp.tar"}
- {"dt": "2025-07-03T15:06:23.002012", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-9q9dcim4-1"}
- {"dt": "2025-07-03T15:06:23.042268", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-07-03T15:06:23.042350", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
