- {"dt": "2025-04-04T18:10:56.442321", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0100"}
- {"dt": "2025-04-04T18:10:56.442410", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-04-04T18:10:56.442509", "lvl": "info", "msg": "Start time: 2025-04-04 18:10:56.442502+00:00 (UTC)"}
- {"dt": "2025-04-04T18:10:56.443328", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst exists"}
- {"dt": "2025-04-04T18:10:56.443704", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/Image exists"}
- {"dt": "2025-04-04T18:10:56.443820", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/rootfs.ext2.zst exists"}
- {"dt": "2025-04-04T18:10:56.453095", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:9.2.0+ds-2, host architecture: amd64"}
- {"dt": "2025-04-04T18:10:56.453337", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2025-04-04T18:10:56.453526", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2025-04-04T18:10:56.453601", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-04-04T18:10:56.453735", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.453836", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.454036", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas"}
- {"dt": "2025-04-04T18:10:56.454190", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin"}
- {"dt": "2025-04-04T18:10:56.454285", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/tests"}
- {"dt": "2025-04-04T18:10:56.454375", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/results"}
- {"dt": "2025-04-04T18:10:56.454448", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-add-keys"}
- {"dt": "2025-04-04T18:10:56.454672", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-add-sources"}
- {"dt": "2025-04-04T18:10:56.454798", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-04-04T18:10:56.454920", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-04-04T18:10:56.455041", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-common-functions"}
- {"dt": "2025-04-04T18:10:56.455157", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-04-04T18:10:56.455272", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-install-packages"}
- {"dt": "2025-04-04T18:10:56.455386", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-04-04T18:10:56.455496", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-os-build"}
- {"dt": "2025-04-04T18:10:56.455705", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-04-04T18:10:56.455916", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-04-04T18:10:56.456127", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-target-ip"}
- {"dt": "2025-04-04T18:10:56.456326", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-target-mac"}
- {"dt": "2025-04-04T18:10:56.456523", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-target-storage"}
- {"dt": "2025-04-04T18:10:56.456727", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-case"}
- {"dt": "2025-04-04T18:10:56.456927", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-event"}
- {"dt": "2025-04-04T18:10:56.457125", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-04-04T18:10:56.457321", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-raise"}
- {"dt": "2025-04-04T18:10:56.457519", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-reference"}
- {"dt": "2025-04-04T18:10:56.457716", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-runner"}
- {"dt": "2025-04-04T18:10:56.457923", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-set"}
- {"dt": "2025-04-04T18:10:56.458121", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-test-shell"}
- {"dt": "2025-04-04T18:10:56.458237", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-04-04T18:10:56.458461", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-04-04T18:10:56.458658", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/environment"}
- {"dt": "2025-04-04T18:10:56.458748", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-04-04T18:10:56.458797", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-04-04T18:10:56.458839", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-04-04T18:10:56.458911", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.458956", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-04-04T18:10:56.459007", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459061", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459100", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-04-04T18:10:56.459147", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459198", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459245", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-04-04T18:10:56.459301", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459354", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459408", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:10:56.459522", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst"}
- {"dt": "2025-04-04T18:10:56.459572", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/url-repo-action-rusjrbvq/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-04T18:10:56.459612", "lvl": "debug", "msg": "total size: 13699163 (13\u202FMB)"}
- {"dt": "2025-04-04T18:10:56.459652", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-04T18:10:56.508583", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (266.86\u202FMB/s)"}
- {"dt": "2025-04-04T18:10:56.508829", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.508920", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.05", "result": "pass", "extra": {"label": "url_repo", "size": ! "13699163", "sha256sum": "c698fbe2d59f0074b6bb5452396ed885d6a88758ddf2e4bb9581b86740287da9"}}}
- {"dt": "2025-04-04T18:10:56.509087", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.509165", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-04-04T18:10:56.509275", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-04T18:10:56.509355", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/url-repo-action-rusjrbvq/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-04T18:10:56.700289", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.yaml"}
- {"dt": "2025-04-04T18:10:56.700678", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-04-04T18:10:56.700771", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.700849", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.701466", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.701525", "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": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst"}}}
- {"dt": "2025-04-04T18:10:56.701651", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.702526", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.702584", "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": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-04-04T18:10:56.702711", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:10:56.710947", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0/tests/0_ltp-smoke test_uuid 1_1.1.3.1"}
- {"dt": "2025-04-04T18:10:56.711035", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2025-04-04T18:10:56.711103", "lvl": "debug", "msg": "TST_CMDFILES='smoketest'"}
- {"dt": "2025-04-04T18:10:56.711170", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-04T18:10:56.711233", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-04-04T18:10:56.711296", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2025-04-04T18:10:56.711359", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2025-04-04T18:10:56.711425", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-04-04T18:10:56.711490", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-04-04T18:10:56.711553", "lvl": "debug", "msg": "RUNNER=''"}
- {"dt": "2025-04-04T18:10:56.711614", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2025-04-04T18:10:56.711747", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.711805", "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": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0/tests/0_ltp-smoke/run.sh"}}}
- {"dt": "2025-04-04T18:10:56.711907", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-04-04T18:10:56.711956", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/lava-overlay-omgnboas/lava-1/0 for stage 0"}
- {"dt": "2025-04-04T18:10:56.712042", "lvl": "debug", "msg": "- 0_ltp-smoke"}
- {"dt": "2025-04-04T18:10:56.712128", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:10:56.712196", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:02.195431", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:05) [common]"}
- {"dt": "2025-04-04T18:11:02.195559", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-04-04T18:11:02.195624", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:14:54) [common]"}
- {"dt": "2025-04-04T18:11:02.195680", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-04-04T18:11:02.195739", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:02.195792", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:14:54) [common]"}
- {"dt": "2025-04-04T18:11:02.195842", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:02.195894", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-04T18:11:02.195946", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:11:02.196087", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/Image"}
- {"dt": "2025-04-04T18:11:02.196146", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/kernel/Image"}
- {"dt": "2025-04-04T18:11:02.196189", "lvl": "debug", "msg": "total size: 12300800 (11\u202FMB)"}
- {"dt": "2025-04-04T18:11:02.196231", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-04-04T18:11:02.208919", "lvl": "info", "msg": "11\u202FMB downloaded in 0.01\u202Fs (921.71\u202FMB/s)"}
- {"dt": "2025-04-04T18:11:02.209013", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:02.209065", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.01", "result": "pass", "extra": {"label": "kernel", "size": ! "12300800", "sha256sum": "04b23b496e69dd35b2be72444e2857ec2cc1d1c9b12bfa1e89bad1547cf85743"}}}
- {"dt": "2025-04-04T18:11:02.209162", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:02.209220", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-04T18:11:02.209275", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:11:02.209382", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/rootfs.ext2.zst"}
- {"dt": "2025-04-04T18:11:02.209433", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/rootfs/rootfs.ext2"}
- {"dt": "2025-04-04T18:11:02.209475", "lvl": "debug", "msg": "total size: 76370187 (72\u202FMB)"}
- {"dt": "2025-04-04T18:11:02.209515", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-04T18:11:02.309845", "lvl": "debug", "msg": "progress  24\u202F% (17\u202FMB)"}
- {"dt": "2025-04-04T18:11:02.410367", "lvl": "debug", "msg": "progress  47\u202F% (34\u202FMB)"}
- {"dt": "2025-04-04T18:11:02.510576", "lvl": "debug", "msg": "progress  69\u202F% (50\u202FMB)"}
- {"dt": "2025-04-04T18:11:02.817301", "lvl": "info", "msg": "72\u202FMB downloaded in 0.61\u202Fs (119.83\u202FMB/s)"}
- {"dt": "2025-04-04T18:11:02.817549", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2025-04-04T18:11:02.817656", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.61", "result": "pass", "extra": {"label": "rootfs", "size": ! "76370187", "sha256sum": "197e32a3bd6f9a7a11f55f2be6ba78953955280f7823825e940c6157c87264d1"}}}
- {"dt": "2025-04-04T18:11:02.817908", "lvl": "debug", "msg": "start: 1.5.2 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:11:02.817993", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/rootfs/rootfs.ext2'"}
- {"dt": "2025-04-04T18:11:04.561439", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-04-04T18:11:04.562313", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/compress-overlay-45z76fp9/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-04-04T18:11:05.108976", "lvl": "debug", "msg": "end: 1.5.2 append-overlays (duration 00:00:02) [common]"}
- {"dt": "2025-04-04T18:11:05.109369", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:03) [common]"}
- {"dt": "2025-04-04T18:11:05.109475", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:09) [common]"}
- {"dt": "2025-04-04T18:11:05.109570", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:05.109639", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:05.109701", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:05.110039", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu max,pauth-impdef=on -machine virt,virtualization=on,gic-version=3,mte=on -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/rootfs/rootfs.ext2,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-04-04T18:11:05.113471", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-04-04T18:11:05.113866", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:05.113969", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "1:9.2.0+ds-2", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2025-04-04T18:11:05.114138", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:05.114241", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:05.114342", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-04-04T18:11:05.114420", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2025-04-04T18:11:05.320713", "lvl": "target", "msg": "Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2025-04-04T18:11:05.320910", "lvl": "target", "msg": "Linux version 6.4.16 (anders@monster) (aarch64-linux-gcc.br_real (Buildroot 2021.11-12449-g1bef613319) 14.2.0, GNU ld (GNU Binutils) 2.42) #1 SMP Fri Apr  4 19:29:47 CEST 2025"}
- {"dt": "2025-04-04T18:11:05.320983", "lvl": "target", "msg": "random: crng init done"}
- {"dt": "2025-04-04T18:11:05.321048", "lvl": "target", "msg": "Machine model: linux,dummy-virt"}
- {"dt": "2025-04-04T18:11:05.321101", "lvl": "target", "msg": "efi: UEFI not found."}
- {"dt": "2025-04-04T18:11:05.321239", "lvl": "target", "msg": "earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2025-04-04T18:11:05.321310", "lvl": "target", "msg": "printk: bootconsole [pl11] enabled"}
- {"dt": "2025-04-04T18:11:05.321569", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-04T18:11:05.321637", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-04-04T18:11:05.321700", "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-04-04T18:11:05.321796", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-04-04T18:11:05.321888", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-04-04T18:11:05.321953", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-04-04T18:11:05.322000", "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-04-04T18:11:05.322083", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2025-04-04T18:11:05.322137", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-04T18:11:05.325156", "lvl": "target", "msg": "Zone ranges:"}
- {"dt": "2025-04-04T18:11:05.326009", "lvl": "target", "msg": "  DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2025-04-04T18:11:05.326071", "lvl": "target", "msg": "  DMA32    empty"}
- {"dt": "2025-04-04T18:11:05.326229", "lvl": "target", "msg": "  Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2025-04-04T18:11:05.326383", "lvl": "target", "msg": "Movable zone start for each node"}
- {"dt": "2025-04-04T18:11:05.326501", "lvl": "target", "msg": "Early memory node ranges"}
- {"dt": "2025-04-04T18:11:05.326749", "lvl": "target", "msg": "  node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-04-04T18:11:05.327220", "lvl": "target", "msg": "Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-04-04T18:11:05.353037", "lvl": "target", "msg": "psci: probing for conduit method from DT."}
- {"dt": "2025-04-04T18:11:05.353235", "lvl": "target", "msg": "psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-04-04T18:11:05.353315", "lvl": "target", "msg": "psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-04-04T18:11:05.353427", "lvl": "target", "msg": "psci: Trusted OS migration not required"}
- {"dt": "2025-04-04T18:11:05.353544", "lvl": "target", "msg": "psci: SMC Calling Convention v1.0"}
- {"dt": "2025-04-04T18:11:05.355681", "lvl": "target", "msg": "percpu: Embedded 18 pages/cpu s35112 r8192 d30424 u73728"}
- {"dt": "2025-04-04T18:11:05.356220", "lvl": "target", "msg": "pcpu-alloc: s35112 r8192 d30424 u73728 alloc=18*4096"}
- {"dt": "2025-04-04T18:11:05.356455", "lvl": "target", "msg": "pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-04-04T18:11:05.357623", "lvl": "target", "msg": "Detected PIPT I-cache on CPU0"}
- {"dt": "2025-04-04T18:11:05.361405", "lvl": "target", "msg": "CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2025-04-04T18:11:05.361990", "lvl": "target", "msg": "CPU features: detected: GIC system register CPU interface"}
- {"dt": "2025-04-04T18:11:05.362108", "lvl": "target", "msg": "CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2025-04-04T18:11:05.362351", "lvl": "target", "msg": "CPU features: detected: Hardware dirty bit management"}
- {"dt": "2025-04-04T18:11:05.362480", "lvl": "target", "msg": "CPU features: detected: Memory Tagging Extension"}
- {"dt": "2025-04-04T18:11:05.362620", "lvl": "target", "msg": "CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2025-04-04T18:11:05.362773", "lvl": "target", "msg": "CPU features: detected: Spectre-v4"}
- {"dt": "2025-04-04T18:11:05.365114", "lvl": "target", "msg": "alternatives: applying boot alternatives"}
- {"dt": "2025-04-04T18:11:05.367822", "lvl": "target", "msg": "Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon"}
- {"dt": "2025-04-04T18:11:05.369853", "lvl": "target", "msg": "<5>Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-04-04T18:11:05.374148", "lvl": "target", "msg": "<6>Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.375152", "lvl": "target", "msg": "<6>Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.375786", "lvl": "target", "msg": "<6>Built 1 zonelists, mobility grouping on.  Total pages: 1032192"}
- {"dt": "2025-04-04T18:11:05.376183", "lvl": "target", "msg": "<6>mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-04-04T18:11:05.376420", "lvl": "target", "msg": "<6>software IO TLB: area num 2."}
- {"dt": "2025-04-04T18:11:05.423556", "lvl": "target", "msg": "<6>software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB)"}
- {"dt": "2025-04-04T18:11:05.438577", "lvl": "target", "msg": "<6>Memory: 4034392K/4194304K available (7744K kernel code, 876K rwdata, 1836K rodata, 1408K init, 438K bss, 159912K reserved, 0K cma-reserved)"}
- {"dt": "2025-04-04T18:11:05.442501", "lvl": "target", "msg": "<6>SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-04-04T18:11:05.447950", "lvl": "target", "msg": "<6>rcu: Hierarchical RCU implementation."}
- {"dt": "2025-04-04T18:11:05.448060", "lvl": "target", "msg": "<6>rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2025-04-04T18:11:05.448309", "lvl": "target", "msg": "<6>rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2025-04-04T18:11:05.448487", "lvl": "target", "msg": "<6>rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-04-04T18:11:05.449597", "lvl": "target", "msg": "<6>NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2025-04-04T18:11:05.453877", "lvl": "target", "msg": "<6>GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2025-04-04T18:11:05.453971", "lvl": "target", "msg": "<6>GICv3: 256 SPIs implemented"}
- {"dt": "2025-04-04T18:11:05.454066", "lvl": "target", "msg": "<6>GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-04-04T18:11:05.455354", "lvl": "target", "msg": "<6>Root IRQ handler: gic_handle_irq"}
- {"dt": "2025-04-04T18:11:05.455507", "lvl": "target", "msg": "<6>GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-04-04T18:11:05.456073", "lvl": "target", "msg": "<6>GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2025-04-04T18:11:05.459544", "lvl": "target", "msg": "<6>ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2025-04-04T18:11:05.460220", "lvl": "target", "msg": "<6>ITS@0x0000000008080000: allocated 8192 Devices @100040000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-04-04T18:11:05.460504", "lvl": "target", "msg": "<6>ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100050000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-04-04T18:11:05.461140", "lvl": "target", "msg": "<6>GICv3: using LPI property table @0x0000000100060000"}
- {"dt": "2025-04-04T18:11:05.461779", "lvl": "target", "msg": "<6>GICv3: CPU0: using allocated LPI pending table @0x0000000100070000"}
- {"dt": "2025-04-04T18:11:05.463563", "lvl": "target", "msg": "<6>rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-04-04T18:11:05.467339", "lvl": "target", "msg": "<6>arch_timer: cp15 timer(s) running at 1000.00MHz (phys)."}
- {"dt": "2025-04-04T18:11:05.467779", "lvl": "target", "msg": "<6>clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns"}
- {"dt": "2025-04-04T18:11:05.468268", "lvl": "target", "msg": "<6>sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns"}
- {"dt": "2025-04-04T18:11:05.481770", "lvl": "target", "msg": "<6>Console: colour dummy device 80x25"}
- {"dt": "2025-04-04T18:11:05.483647", "lvl": "target", "msg": "<6>Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)"}
- {"dt": "2025-04-04T18:11:05.483922", "lvl": "target", "msg": "<6>pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-04-04T18:11:05.487610", "lvl": "target", "msg": "<6>Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.487786", "lvl": "target", "msg": "<6>Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.510856", "lvl": "target", "msg": "<4>cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-04-04T18:11:05.518076", "lvl": "target", "msg": "<6>rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-04-04T18:11:05.518198", "lvl": "target", "msg": "<6>rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-04-04T18:11:05.521038", "lvl": "target", "msg": "<6>Platform MSI: its@8080000 domain created"}
- {"dt": "2025-04-04T18:11:05.521599", "lvl": "target", "msg": "<6>PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2025-04-04T18:11:05.521679", "lvl": "target", "msg": "<6>EFI services will not be available."}
- {"dt": "2025-04-04T18:11:05.523189", "lvl": "target", "msg": "<6>smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-04-04T18:11:05.537018", "lvl": "target", "msg": "<6>Detected PIPT I-cache on CPU1"}
- {"dt": "2025-04-04T18:11:05.537088", "lvl": "target", "msg": "<6>GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2025-04-04T18:11:05.537156", "lvl": "target", "msg": "<6>GICv3: CPU1: using allocated LPI pending table @0x0000000100080000"}
- {"dt": "2025-04-04T18:11:05.537304", "lvl": "target", "msg": "<6>CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2025-04-04T18:11:05.537424", "lvl": "target", "msg": "<6>smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-04-04T18:11:05.537571", "lvl": "target", "msg": "<6>SMP: Total of 2 processors activated."}
- {"dt": "2025-04-04T18:11:05.537882", "lvl": "target", "msg": "<6>CPU features: detected: Branch Target Identification"}
- {"dt": "2025-04-04T18:11:05.538053", "lvl": "target", "msg": "<6>CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2025-04-04T18:11:05.538230", "lvl": "target", "msg": "<6>CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2025-04-04T18:11:05.538485", "lvl": "target", "msg": "<6>CPU features: detected: Instruction cache invalidation not required for I/D coherence"}
- {"dt": "2025-04-04T18:11:05.538815", "lvl": "target", "msg": "<6>CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2025-04-04T18:11:05.539361", "lvl": "target", "msg": "<6>CPU features: detected: Common not Private translations"}
- {"dt": "2025-04-04T18:11:05.539460", "lvl": "target", "msg": "<6>CPU features: detected: CRC32 instructions"}
- {"dt": "2025-04-04T18:11:05.539648", "lvl": "target", "msg": "<6>CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2025-04-04T18:11:05.539745", "lvl": "target", "msg": "<6>CPU features: detected: E0PD"}
- {"dt": "2025-04-04T18:11:05.539916", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Counter Virtualization"}
- {"dt": "2025-04-04T18:11:05.540115", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)"}
- {"dt": "2025-04-04T18:11:05.540284", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2025-04-04T18:11:05.540560", "lvl": "target", "msg": "<6>CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2025-04-04T18:11:05.540726", "lvl": "target", "msg": "<6>CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2025-04-04T18:11:05.540868", "lvl": "target", "msg": "<6>CPU features: detected: LSE atomic instructions"}
- {"dt": "2025-04-04T18:11:05.541013", "lvl": "target", "msg": "<6>CPU features: detected: Privileged Access Never"}
- {"dt": "2025-04-04T18:11:05.541145", "lvl": "target", "msg": "<6>CPU features: detected: RAS Extension Support"}
- {"dt": "2025-04-04T18:11:05.541291", "lvl": "target", "msg": "<6>CPU features: detected: Random Number Generator"}
- {"dt": "2025-04-04T18:11:05.541448", "lvl": "target", "msg": "<6>CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2025-04-04T18:11:05.541634", "lvl": "target", "msg": "<6>CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2025-04-04T18:11:05.541972", "lvl": "target", "msg": "<6>CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2025-04-04T18:11:05.542042", "lvl": "target", "msg": "<6>CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2025-04-04T18:11:05.542333", "lvl": "target", "msg": "<6>CPU features: detected: WFx with timeout"}
- {"dt": "2025-04-04T18:11:05.542381", "lvl": "target", "msg": "<6>CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2025-04-04T18:11:05.542430", "lvl": "target", "msg": "<6>CPU features: detected: FA64"}
- {"dt": "2025-04-04T18:11:05.542603", "lvl": "target", "msg": "<6>CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2025-04-04T18:11:05.542760", "lvl": "target", "msg": "<6>CPU features: detected: Scalable Vector Extension"}
- {"dt": "2025-04-04T18:11:05.552430", "lvl": "target", "msg": "<6>SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2025-04-04T18:11:05.553171", "lvl": "target", "msg": "<6>SVE: default vector length 64 bytes per vector"}
- {"dt": "2025-04-04T18:11:05.554547", "lvl": "target", "msg": "<6>SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2025-04-04T18:11:05.554608", "lvl": "target", "msg": "<6>SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2025-04-04T18:11:05.554748", "lvl": "target", "msg": "<6>SME: default vector length 32 bytes per vector"}
- {"dt": "2025-04-04T18:11:05.555274", "lvl": "target", "msg": "<6>CPU: All CPU(s) started at EL2"}
- {"dt": "2025-04-04T18:11:05.555335", "lvl": "target", "msg": "<6>alternatives: applying system-wide alternatives"}
- {"dt": "2025-04-04T18:11:05.593774", "lvl": "target", "msg": "<6>devtmpfs: initialized"}
- {"dt": "2025-04-04T18:11:05.604952", "lvl": "target", "msg": "<6>clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2025-04-04T18:11:05.605408", "lvl": "target", "msg": "<6>futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.607758", "lvl": "target", "msg": "<6>DMI not present or invalid."}
- {"dt": "2025-04-04T18:11:05.611537", "lvl": "target", "msg": "<6>NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-04-04T18:11:05.615251", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2025-04-04T18:11:05.615979", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2025-04-04T18:11:05.616180", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2025-04-04T18:11:05.620461", "lvl": "target", "msg": "<6>thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-04-04T18:11:05.620538", "lvl": "target", "msg": "<6>cpuidle: using governor menu"}
- {"dt": "2025-04-04T18:11:05.621340", "lvl": "target", "msg": "<6>hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2025-04-04T18:11:05.621875", "lvl": "target", "msg": "<6>ASID allocator initialised with 65536 entries"}
- {"dt": "2025-04-04T18:11:05.621978", "lvl": "target", "msg": "<6>Serial: AMBA PL011 UART driver"}
- {"dt": "2025-04-04T18:11:05.645831", "lvl": "target", "msg": "<6>9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 15, base_baud = 0) is a PL011 rev1"}
- {"dt": "2025-04-04T18:11:05.647263", "lvl": "target", "msg": "<6>printk: console [ttyAMA0] enabled"}
- {"dt": "2025-04-04T18:11:05.647324", "lvl": "target", "msg": "<6>printk: console [ttyAMA0] enabled"}
- {"dt": "2025-04-04T18:11:05.647492", "lvl": "target", "msg": "<6>printk: bootconsole [pl11] disabled"}
- {"dt": "2025-04-04T18:11:05.647651", "lvl": "target", "msg": "<6>printk: bootconsole [pl11] disabled"}
- {"dt": "2025-04-04T18:11:05.660512", "lvl": "target", "msg": "<6>ACPI: Interpreter disabled."}
- {"dt": "2025-04-04T18:11:05.661096", "lvl": "target", "msg": "<6>iommu: Default domain type: Translated "}
- {"dt": "2025-04-04T18:11:05.661186", "lvl": "target", "msg": "<6>iommu: DMA domain TLB invalidation policy: strict mode "}
- {"dt": "2025-04-04T18:11:05.662268", "lvl": "target", "msg": "<5>SCSI subsystem initialized"}
- {"dt": "2025-04-04T18:11:05.662921", "lvl": "target", "msg": "<7>libata version 3.00 loaded."}
- {"dt": "2025-04-04T18:11:05.663183", "lvl": "target", "msg": "<6>pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-04-04T18:11:05.663357", "lvl": "target", "msg": "<6>pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-04-04T18:11:05.663626", "lvl": "target", "msg": "<6>PTP clock support registered"}
- {"dt": "2025-04-04T18:11:05.670958", "lvl": "target", "msg": "<6>vgaarb: loaded"}
- {"dt": "2025-04-04T18:11:05.671891", "lvl": "target", "msg": "<6>clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-04-04T18:11:05.674052", "lvl": "target", "msg": "<6>pnp: PnP ACPI: disabled"}
- {"dt": "2025-04-04T18:11:05.697175", "lvl": "target", "msg": "<6>NET: Registered PF_INET protocol family"}
- {"dt": "2025-04-04T18:11:05.698440", "lvl": "target", "msg": "<6>IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.704649", "lvl": "target", "msg": "<6>tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.704807", "lvl": "target", "msg": "<6>Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.705065", "lvl": "target", "msg": "<6>TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.705456", "lvl": "target", "msg": "<6>TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.706324", "lvl": "target", "msg": "<6>TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2025-04-04T18:11:05.707291", "lvl": "target", "msg": "<6>UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.707818", "lvl": "target", "msg": "<6>UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-04T18:11:05.708746", "lvl": "target", "msg": "<6>NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-04-04T18:11:05.709060", "lvl": "target", "msg": "<6>PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-04-04T18:11:05.714241", "lvl": "target", "msg": "<6>workingset: timestamp_bits=46 max_order=20 bucket_order=0"}
- {"dt": "2025-04-04T18:11:05.715485", "lvl": "target", "msg": "<6>fuse: init (API version 7.38)"}
- {"dt": "2025-04-04T18:11:05.717326", "lvl": "target", "msg": "<6>Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)"}
- {"dt": "2025-04-04T18:11:05.717460", "lvl": "target", "msg": "<6>io scheduler mq-deadline registered"}
- {"dt": "2025-04-04T18:11:05.717597", "lvl": "target", "msg": "<6>io scheduler kyber registered"}
- {"dt": "2025-04-04T18:11:05.720067", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2025-04-04T18:11:05.720813", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2025-04-04T18:11:05.721387", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2025-04-04T18:11:05.721685", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2025-04-04T18:11:05.722284", "lvl": "target", "msg": "<4>pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-04-04T18:11:05.724195", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2025-04-04T18:11:05.725293", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2025-04-04T18:11:05.725571", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-04-04T18:11:05.725814", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-04-04T18:11:05.725987", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-04-04T18:11:05.726187", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-04-04T18:11:05.727497", "lvl": "target", "msg": "<6>pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000"}
- {"dt": "2025-04-04T18:11:05.730309", "lvl": "target", "msg": "<6>pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000"}
- {"dt": "2025-04-04T18:11:05.730484", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x10: [io  0x0000-0x007f]"}
- {"dt": "2025-04-04T18:11:05.730672", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-04-04T18:11:05.730966", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-04-04T18:11:05.733301", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]"}
- {"dt": "2025-04-04T18:11:05.733597", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff]"}
- {"dt": "2025-04-04T18:11:05.733721", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 0: assigned [io  0x1000-0x107f]"}
- {"dt": "2025-04-04T18:11:05.742043", "lvl": "target", "msg": "<6>virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)"}
- {"dt": "2025-04-04T18:11:05.823213", "lvl": "target", "msg": "<6>virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-04-04T18:11:05.833584", "lvl": "target", "msg": "<5>virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2025-04-04T18:11:05.849639", "lvl": "target", "msg": "<6>rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2025-04-04T18:11:05.850483", "lvl": "target", "msg": "<6>rtc-pl031 9010000.pl031: setting system clock to 2025-04-04T18:11:05 UTC (1743790265)"}
- {"dt": "2025-04-04T18:11:05.856364", "lvl": "target", "msg": "<6>hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available"}
- {"dt": "2025-04-04T18:11:05.857535", "lvl": "target", "msg": "<6>NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-04-04T18:11:05.862661", "lvl": "target", "msg": "<6>Segment Routing with IPv6"}
- {"dt": "2025-04-04T18:11:05.862762", "lvl": "target", "msg": "<6>In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-04-04T18:11:05.863107", "lvl": "target", "msg": "<6>sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-04-04T18:11:05.865349", "lvl": "target", "msg": "<6>NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-04-04T18:11:05.865449", "lvl": "target", "msg": "<6>NET: Registered PF_KEY protocol family"}
- {"dt": "2025-04-04T18:11:05.866044", "lvl": "target", "msg": "<6>NET: Registered PF_VSOCK protocol family"}
- {"dt": "2025-04-04T18:11:05.875074", "lvl": "target", "msg": "<6>registered taskstats version 1"}
- {"dt": "2025-04-04T18:11:05.888552", "lvl": "target", "msg": "<6>clk: Disabling unused clocks"}
- {"dt": "2025-04-04T18:11:05.924403", "lvl": "target", "msg": "<6>EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2025-04-04T18:11:05.925206", "lvl": "target", "msg": "<6>EXT4-fs (vda): mounted filesystem fe3857ec-995d-426f-bd03-abe1acdef705 ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-04-04T18:11:05.925510", "lvl": "target", "msg": "<6>VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2025-04-04T18:11:05.926849", "lvl": "target", "msg": "<6>devtmpfs: mounted"}
- {"dt": "2025-04-04T18:11:05.948178", "lvl": "target", "msg": "<6>Freeing unused kernel memory: 1408K"}
- {"dt": "2025-04-04T18:11:05.957116", "lvl": "target", "msg": "<6>Run /sbin/init as init process"}
- {"dt": "2025-04-04T18:11:05.957205", "lvl": "target", "msg": "<7>  with arguments:"}
- {"dt": "2025-04-04T18:11:05.957272", "lvl": "target", "msg": "<7>    /sbin/init"}
- {"dt": "2025-04-04T18:11:05.957328", "lvl": "target", "msg": "<7>    verbose"}
- {"dt": "2025-04-04T18:11:05.957395", "lvl": "target", "msg": "<7>  with environment:"}
- {"dt": "2025-04-04T18:11:05.957450", "lvl": "target", "msg": "<7>    HOME=/"}
- {"dt": "2025-04-04T18:11:05.957500", "lvl": "target", "msg": "<7>    TERM=linux"}
- {"dt": "2025-04-04T18:11:06.056767", "lvl": "target", "msg": "<6>EXT4-fs (vda): re-mounted fe3857ec-995d-426f-bd03-abe1acdef705 r/w. Quota mode: disabled."}
- {"dt": "2025-04-04T18:11:06.286728", "lvl": "target", "msg": "Saving 256 bits of creditable seed for next boot"}
- {"dt": "2025-04-04T18:11:06.366805", "lvl": "target", "msg": "Starting syslogd: OK"}
- {"dt": "2025-04-04T18:11:06.396053", "lvl": "target", "msg": "Starting klogd: OK"}
- {"dt": "2025-04-04T18:11:06.465341", "lvl": "target", "msg": "Running sysctl: OK"}
- {"dt": "2025-04-04T18:11:06.623014", "lvl": "target", "msg": "Starting network: OK"}
- {"dt": "2025-04-04T18:11:06.657827", "lvl": "target", "msg": "Starting crond: OK"}
- {"dt": "2025-04-04T18:11:06.773955", "lvl": "target", "msg": "\r"}
- {"dt": "2025-04-04T18:11:06.775136", "lvl": "target", "msg": "Welcome to TuxTest"}
- {"dt": "2025-04-04T18:11:06.775433", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-04-04T18:11:06.775501", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.45", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-04-04T18:11:06.775592", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-04-04T18:11:06.775642", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-04-04T18:11:06.875870", "lvl": "input", "msg": "root"}
- {"dt": "2025-04-04T18:11:06.876047", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-04T18:11:06.876131", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:14:58)"}
- {"dt": "2025-04-04T18:11:06.876195", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2025-04-04T18:11:06.876544", "lvl": "target", "msg": "\rtuxtest login: root"}
- {"dt": "2025-04-04T18:11:06.931286", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-04T18:11:06.931472", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-04T18:11:06.931563", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.61", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-04-04T18:11:06.931699", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-04T18:11:06.931791", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:14:58) [common]"}
- {"dt": "2025-04-04T18:11:06.931862", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-04T18:11:06.931925", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-04-04T18:11:06.931986", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-04-04T18:11:06.982113", "lvl": "input", "msg": ""}
- {"dt": "2025-04-04T18:11:06.982277", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2025-04-04T18:11:06.982351", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-04T18:11:06.983531", "lvl": "target", "msg": "root@tuxtest:~# "}
- {"dt": "2025-04-04T18:11:06.984914", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:06.984991", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:14:58) [common]"}
- {"dt": "2025-04-04T18:11:06.985064", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:06.985128", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2025-04-04T18:11:06.985188", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:02:00) [common]"}
- {"dt": "2025-04-04T18:11:06.985244", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:02:00) [common]"}
- {"dt": "2025-04-04T18:11:06.985292", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-04-04T18:11:06.985343", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-04-04T18:11:07.085535", "lvl": "input", "msg": "#"}
- {"dt": "2025-04-04T18:11:07.085648", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:02:00)"}
- {"dt": "2025-04-04T18:11:07.086313", "lvl": "target", "msg": "root@tuxtest:~# #"}
- {"dt": "2025-04-04T18:11:07.088155", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-04-04T18:11:07.088261", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-04-04T18:11:07.188553", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-04-04T18:11:07.189038", "lvl": "target", "msg": "root@tuxtest:~# export SHELL=/bin/sh"}
- {"dt": "2025-04-04T18:11:07.190241", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-04-04T18:11:07.290474", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-04-04T18:11:07.291092", "lvl": "target", "msg": "root@tuxtest:~# . /lava-1/environment"}
- {"dt": "2025-04-04T18:11:07.293379", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-04-04T18:11:07.393639", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-04T18:11:07.393775", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-04-04T18:11:07.394462", "lvl": "target", "msg": "root@tuxtest:~# /lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-04T18:11:07.498299", "lvl": "target", "msg": "+ export TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-04T18:11:07.498480", "lvl": "target", "msg": "+ TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-04T18:11:07.498652", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-04T18:11:07.501790", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2025-04-04T18:11:07.507463", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-04-04T18:11:07.507733", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-04T18:11:07.509664", "lvl": "target", "msg": "<8><LAVA_SIGNAL_STARTRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-04T18:11:07.509924", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-04T18:11:07.510002", "lvl": "info", "msg": "Starting test lava.0_ltp-smoke (1_1.1.3.1)"}
- {"dt": "2025-04-04T18:11:07.510089", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-04-04T18:11:07.510481", "lvl": "target", "msg": "+ cd ./automated/linux/ltp/"}
- {"dt": "2025-04-04T18:11:07.514132", "lvl": "target", "msg": "+ ./ltp.sh -T smoketest -s true -v '' -M 5 -R root -r '' -b '' -d /scratch -g '' -e production -i /opt/ltp/ -S /tuxtest/skipfiles/arm64/ltp-smoke -p '' -u '' -t false -n 1 -c 1"}
- {"dt": "2025-04-04T18:11:07.524447", "lvl": "target", "msg": "+ . ../../lib/sh-test-lib"}
- {"dt": "2025-04-04T18:11:07.525910", "lvl": "target", "msg": "++ LANG=C"}
- {"dt": "2025-04-04T18:11:07.526393", "lvl": "target", "msg": "++ export LANG"}
- {"dt": "2025-04-04T18:11:07.538659", "lvl": "target", "msg": "++ export PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-04T18:11:07.538752", "lvl": "target", "msg": "++ PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-04T18:11:07.543403", "lvl": "target", "msg": "++ kernel_config="}
- {"dt": "2025-04-04T18:11:07.547959", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-04T18:11:07.550332", "lvl": "target", "msg": "+ OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-04T18:11:07.551238", "lvl": "target", "msg": "+ RESULT_FILE=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/result.txt"}
- {"dt": "2025-04-04T18:11:07.553911", "lvl": "target", "msg": "++ readlink -f ./ltp.sh"}
- {"dt": "2025-04-04T18:11:07.560996", "lvl": "target", "msg": "+ SCRIPT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-04T18:11:07.564433", "lvl": "target", "msg": "++ dirname /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-04T18:11:07.570410", "lvl": "target", "msg": "+ SCRIPTPATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-04T18:11:07.570965", "lvl": "target", "msg": "+ echo 'Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp'"}
- {"dt": "2025-04-04T18:11:07.571413", "lvl": "target", "msg": "Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-04T18:11:07.571641", "lvl": "target", "msg": "+ TST_CMDFILES="}
- {"dt": "2025-04-04T18:11:07.571865", "lvl": "target", "msg": "+ SKIPFILE="}
- {"dt": "2025-04-04T18:11:07.572120", "lvl": "target", "msg": "+ SKIPFILE_YAML="}
- {"dt": "2025-04-04T18:11:07.572167", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-04T18:11:07.572223", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-04T18:11:07.572320", "lvl": "target", "msg": "+ ENVIRONMENT="}
- {"dt": "2025-04-04T18:11:07.572541", "lvl": "target", "msg": "+ LTP_VERSION=20180926"}
- {"dt": "2025-04-04T18:11:07.572593", "lvl": "target", "msg": "+ TEST_PROGRAM=ltp"}
- {"dt": "2025-04-04T18:11:07.572656", "lvl": "target", "msg": "+ TEST_GIT_URL="}
- {"dt": "2025-04-04T18:11:07.575035", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-04T18:11:07.576875", "lvl": "target", "msg": "+ TEST_DIR=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp"}
- {"dt": "2025-04-04T18:11:07.577320", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-04T18:11:07.577368", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-04T18:11:07.577611", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-04T18:11:07.577665", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-04T18:11:07.577879", "lvl": "target", "msg": "+ LTP_TMPDIR=/ltp-tmp"}
- {"dt": "2025-04-04T18:11:07.578097", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp"}
- {"dt": "2025-04-04T18:11:07.580235", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.581420", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.581484", "lvl": "target", "msg": "+ TST_CMDFILES=smoketest"}
- {"dt": "2025-04-04T18:11:07.586515", "lvl": "target", "msg": "++ echo smoketest"}
- {"dt": "2025-04-04T18:11:07.587824", "lvl": "target", "msg": "++ sed 's,\\/,_,'"}
- {"dt": "2025-04-04T18:11:07.597616", "lvl": "target", "msg": "+ LOG_FILE=smoketest"}
- {"dt": "2025-04-04T18:11:07.597919", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.598240", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.598308", "lvl": "target", "msg": "+ SKIP_INSTALL=true"}
- {"dt": "2025-04-04T18:11:07.598548", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.598628", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.598866", "lvl": "target", "msg": "+ LTP_VERSION="}
- {"dt": "2025-04-04T18:11:07.598962", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.599190", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.599256", "lvl": "target", "msg": "+ export LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-04T18:11:07.599318", "lvl": "target", "msg": "+ LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-04T18:11:07.599757", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.600001", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.600094", "lvl": "target", "msg": "+ export PASSWD=root"}
- {"dt": "2025-04-04T18:11:07.600214", "lvl": "target", "msg": "+ PASSWD=root"}
- {"dt": "2025-04-04T18:11:07.600288", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.600540", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.600602", "lvl": "target", "msg": "+ export RUNNER="}
- {"dt": "2025-04-04T18:11:07.600664", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-04T18:11:07.600824", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.601082", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.601139", "lvl": "target", "msg": "+ export BOARD="}
- {"dt": "2025-04-04T18:11:07.601220", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-04T18:11:07.601510", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.601564", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.601643", "lvl": "target", "msg": "+ export LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-04T18:11:07.601729", "lvl": "target", "msg": "+ LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-04T18:11:07.601892", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.601996", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.602240", "lvl": "target", "msg": "+ export BRANCH="}
- {"dt": "2025-04-04T18:11:07.602292", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-04T18:11:07.602541", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.602593", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.602645", "lvl": "target", "msg": "+ export ENVIRONMENT=production"}
- {"dt": "2025-04-04T18:11:07.602752", "lvl": "target", "msg": "+ ENVIRONMENT=production"}
- {"dt": "2025-04-04T18:11:07.603086", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.603135", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.603367", "lvl": "target", "msg": "+ export LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-04T18:11:07.603423", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-04T18:11:07.603759", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.603818", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.604759", "lvl": "target", "msg": "+ '[' -z /tuxtest/skipfiles/arm64/ltp-smoke ']'"}
- {"dt": "2025-04-04T18:11:07.605009", "lvl": "target", "msg": "+ '[' /tuxtest/skipfiles/arm64/ltp-smoke = yaml ']'"}
- {"dt": "2025-04-04T18:11:07.605724", "lvl": "target", "msg": "+ '[' / == / ']'"}
- {"dt": "2025-04-04T18:11:07.606038", "lvl": "target", "msg": "+ SKIPFILE='-S /tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-04T18:11:07.606110", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.606328", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.606960", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-04T18:11:07.607181", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.607249", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.607468", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-04T18:11:07.607769", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.607819", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.607882", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-04T18:11:07.608049", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.608309", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.608573", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-04T18:11:07.608812", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.609047", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-04T18:11:07.609284", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-04T18:11:07.609393", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-04T18:11:07.610302", "lvl": "target", "msg": "+ TEST_TARFILE=https://github.com/linux-test-project/ltp/releases/download//ltp-full-.tar.xz"}
- {"dt": "2025-04-04T18:11:07.610528", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-04T18:11:07.613604", "lvl": "target", "msg": "+ check_root"}
- {"dt": "2025-04-04T18:11:07.616718", "lvl": "target", "msg": "++ id -ru"}
- {"dt": "2025-04-04T18:11:07.622595", "lvl": "target", "msg": "+ '[' 0 -eq 0 ']'"}
- {"dt": "2025-04-04T18:11:07.622698", "lvl": "target", "msg": "+ return 0"}
- {"dt": "2025-04-04T18:11:07.623102", "lvl": "target", "msg": "+ create_out_dir /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-04T18:11:07.623593", "lvl": "target", "msg": "+ '[' -z /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-04T18:11:07.623815", "lvl": "target", "msg": "+ local OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-04T18:11:07.624597", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-04T18:11:07.624853", "lvl": "target", "msg": "+ mkdir -p /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-04T18:11:07.632495", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-04T18:11:07.633260", "lvl": "target", "msg": "+ info_msg 'About to run ltp test...'"}
- {"dt": "2025-04-04T18:11:07.633527", "lvl": "target", "msg": "+ local 'msg=About to run ltp test...'"}
- {"dt": "2025-04-04T18:11:07.633778", "lvl": "target", "msg": "+ '[' -z 'About to run ltp test...' ']'"}
- {"dt": "2025-04-04T18:11:07.634267", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'About to run ltp test...'"}
- {"dt": "2025-04-04T18:11:07.634323", "lvl": "target", "msg": "INFO: About to run ltp test..."}
- {"dt": "2025-04-04T18:11:07.634807", "lvl": "target", "msg": "+ info_msg 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-04T18:11:07.635088", "lvl": "target", "msg": "+ local 'msg=Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-04T18:11:07.635381", "lvl": "target", "msg": "+ '[' -z 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output' ']'"}
- {"dt": "2025-04-04T18:11:07.635834", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-04T18:11:07.636202", "lvl": "target", "msg": "INFO: Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-04T18:11:07.636420", "lvl": "target", "msg": "+ '[' true = true ']'"}
- {"dt": "2025-04-04T18:11:07.636678", "lvl": "target", "msg": "+ info_msg 'ltp installation skipped altogether'"}
- {"dt": "2025-04-04T18:11:07.636911", "lvl": "target", "msg": "+ local 'msg=ltp installation skipped altogether'"}
- {"dt": "2025-04-04T18:11:07.637136", "lvl": "target", "msg": "+ '[' -z 'ltp installation skipped altogether' ']'"}
- {"dt": "2025-04-04T18:11:07.637368", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'ltp installation skipped altogether'"}
- {"dt": "2025-04-04T18:11:07.637411", "lvl": "target", "msg": "INFO: ltp installation skipped altogether"}
- {"dt": "2025-04-04T18:11:07.637857", "lvl": "target", "msg": "+ '[' '!' -d /opt/ltp/ ']'"}
- {"dt": "2025-04-04T18:11:07.638625", "lvl": "target", "msg": "+ info_msg 'Running prep_system'"}
- {"dt": "2025-04-04T18:11:07.638867", "lvl": "target", "msg": "+ local 'msg=Running prep_system'"}
- {"dt": "2025-04-04T18:11:07.638924", "lvl": "target", "msg": "+ '[' -z 'Running prep_system' ']'"}
- {"dt": "2025-04-04T18:11:07.639142", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running prep_system'"}
- {"dt": "2025-04-04T18:11:07.639193", "lvl": "target", "msg": "INFO: Running prep_system"}
- {"dt": "2025-04-04T18:11:07.639407", "lvl": "target", "msg": "+ prep_system"}
- {"dt": "2025-04-04T18:11:07.639815", "lvl": "target", "msg": "+ systemctl is-active systemd-timesyncd"}
- {"dt": "2025-04-04T18:11:07.645580", "lvl": "target", "msg": "+ '[' -f /proc/sys/kernel/unprivileged_userns_clone ']'"}
- {"dt": "2025-04-04T18:11:07.645997", "lvl": "target", "msg": "+ info_msg 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-04T18:11:07.646487", "lvl": "target", "msg": "+ local 'msg=Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-04T18:11:07.646753", "lvl": "target", "msg": "+ '[' -z 'Kernel has no support of unprivileged_userns_clone' ']'"}
- {"dt": "2025-04-04T18:11:07.647021", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-04T18:11:07.647065", "lvl": "target", "msg": "INFO: Kernel has no support of unprivileged_userns_clone"}
- {"dt": "2025-04-04T18:11:07.647332", "lvl": "target", "msg": "+ info_msg 'Running run_ltp'"}
- {"dt": "2025-04-04T18:11:07.647734", "lvl": "target", "msg": "+ local 'msg=Running run_ltp'"}
- {"dt": "2025-04-04T18:11:07.648119", "lvl": "target", "msg": "+ '[' -z 'Running run_ltp' ']'"}
- {"dt": "2025-04-04T18:11:07.648170", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running run_ltp'"}
- {"dt": "2025-04-04T18:11:07.648254", "lvl": "target", "msg": "INFO: Running run_ltp"}
- {"dt": "2025-04-04T18:11:07.648470", "lvl": "target", "msg": "+ run_ltp"}
- {"dt": "2025-04-04T18:11:07.648686", "lvl": "target", "msg": "+ cd /opt/ltp/"}
- {"dt": "2025-04-04T18:11:07.649106", "lvl": "target", "msg": "+ mkdir -m 777 -p /scratch"}
- {"dt": "2025-04-04T18:11:07.657438", "lvl": "target", "msg": "+ for file in ${TST_CMDFILES//,/ }"}
- {"dt": "2025-04-04T18:11:07.657524", "lvl": "target", "msg": "+ cat runtest/smoketest"}
- {"dt": "2025-04-04T18:11:07.667853", "lvl": "target", "msg": "+ sed -i 's/#.*$//;/^$/d' alltests"}
- {"dt": "2025-04-04T18:11:07.682744", "lvl": "target", "msg": "+ split --verbose --numeric-suffixes=1 -n l/1/1 alltests"}
- {"dt": "2025-04-04T18:11:07.692005", "lvl": "target", "msg": "+ echo '============== Tests to run ==============='"}
- {"dt": "2025-04-04T18:11:07.692257", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-04-04T18:11:07.692496", "lvl": "target", "msg": "+ cat runtest/shardfile"}
- {"dt": "2025-04-04T18:11:07.697651", "lvl": "target", "msg": "access01 access01"}
- {"dt": "2025-04-04T18:11:07.697730", "lvl": "target", "msg": "chdir01 chdir01"}
- {"dt": "2025-04-04T18:11:07.697810", "lvl": "target", "msg": "fork01 fork01"}
- {"dt": "2025-04-04T18:11:07.697855", "lvl": "target", "msg": "time01 time01"}
- {"dt": "2025-04-04T18:11:07.697903", "lvl": "target", "msg": "wait02 wait02"}
- {"dt": "2025-04-04T18:11:07.697942", "lvl": "target", "msg": "write01 write01"}
- {"dt": "2025-04-04T18:11:07.697978", "lvl": "target", "msg": "symlink01 symlink01"}
- {"dt": "2025-04-04T18:11:07.698022", "lvl": "target", "msg": "stat04 stat04"}
- {"dt": "2025-04-04T18:11:07.698256", "lvl": "target", "msg": "splice02 splice02 -s 20"}
- {"dt": "2025-04-04T18:11:07.698302", "lvl": "target", "msg": "df01_sh df01.sh"}
- {"dt": "2025-04-04T18:11:07.698532", "lvl": "target", "msg": "shell_test01 echo \"SUCCESS\" | shell_pipe01.sh"}
- {"dt": "2025-04-04T18:11:07.698575", "lvl": "target", "msg": "ping602 ping02.sh -6"}
- {"dt": "2025-04-04T18:11:07.698620", "lvl": "target", "msg": "macsec02 macsec02.sh"}
- {"dt": "2025-04-04T18:11:07.700146", "lvl": "target", "msg": "+ echo '===========End Tests to run ==============='"}
- {"dt": "2025-04-04T18:11:07.700373", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-04-04T18:11:07.700627", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-04T18:11:07.702143", "lvl": "target", "msg": "+ pipe0_status './runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/arm64/ltp-smoke' 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-04T18:11:07.702378", "lvl": "target", "msg": "+ '[' 2 -ne 2 ']'"}
- {"dt": "2025-04-04T18:11:07.703441", "lvl": "target", "msg": "+ local 'cmd1=./runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-04T18:11:07.703709", "lvl": "target", "msg": "+ local 'cmd2=tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-04T18:11:07.703970", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-04T18:11:07.704074", "lvl": "target", "msg": "+ local ret_val"}
- {"dt": "2025-04-04T18:11:07.710917", "lvl": "target", "msg": "++ eval './runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-04T18:11:07.711486", "lvl": "target", "msg": "++ eval 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-04T18:11:07.712567", "lvl": "target", "msg": "+++ ./runltp -p -q -f shardfile -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed -d /scratch -S /tuxtest/skipfiles/arm64/ltp-smoke"}
- {"dt": "2025-04-04T18:11:07.713006", "lvl": "target", "msg": "+++ tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out"}
- {"dt": "2025-04-04T18:11:07.749924", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-04T18:11:07.750063", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-04T18:11:07.750328", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-04T18:11:07.750630", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-04T18:11:07.798166", "lvl": "target", "msg": "INFO: creating /opt/ltp/output directory"}
- {"dt": "2025-04-04T18:11:07.917140", "lvl": "target", "msg": "Checking for required user/group ids"}
- {"dt": "2025-04-04T18:11:07.918573", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:07.994721", "lvl": "target", "msg": "'root' user id and group found."}
- {"dt": "2025-04-04T18:11:07.995446", "lvl": "target", "msg": "'nobody' user id and group found."}
- {"dt": "2025-04-04T18:11:07.996216", "lvl": "target", "msg": "'bin' user id and group found."}
- {"dt": "2025-04-04T18:11:07.996918", "lvl": "target", "msg": "'daemon' user id and group found."}
- {"dt": "2025-04-04T18:11:07.997537", "lvl": "target", "msg": "Users group found."}
- {"dt": "2025-04-04T18:11:07.998963", "lvl": "target", "msg": "Sys group found."}
- {"dt": "2025-04-04T18:11:08.084715", "lvl": "target", "msg": "Required users/groups exist."}
- {"dt": "2025-04-04T18:11:08.086992", "lvl": "target", "msg": "no big block device was specified on commandline."}
- {"dt": "2025-04-04T18:11:08.087101", "lvl": "target", "msg": "Tests which require a big block device are disabled."}
- {"dt": "2025-04-04T18:11:08.087420", "lvl": "target", "msg": "You can specify it with option -z"}
- {"dt": "2025-04-04T18:11:08.096363", "lvl": "target", "msg": "INFO: Test start time: Fri Apr  4 18:11:07 UTC 2025"}
- {"dt": "2025-04-04T18:11:08.097788", "lvl": "target", "msg": "COMMAND:    /opt/ltp/bin/ltp-pan -q  -e -S   -a 117     -n 117 -p -f /scratch/ltp-rHga5SsArH/alltests -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed -T /opt/ltp/output/LTP_RUN_ON-LTP_smoketest.log.tconf"}
- {"dt": "2025-04-04T18:11:08.108232", "lvl": "target", "msg": "LOG File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-04T18:11:08.116470", "lvl": "target", "msg": "FAILED COMMAND File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed"}
- {"dt": "2025-04-04T18:11:08.124778", "lvl": "target", "msg": "TCONF COMMAND File: /opt/ltp/output/LTP_RUN_ON-LTP_smoketest.log.tconf"}
- {"dt": "2025-04-04T18:11:08.125906", "lvl": "target", "msg": "Running tests......."}
- {"dt": "2025-04-04T18:11:08.166139", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-rHga5SsArH/LTP_accbZDlkL as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:08.167624", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:08.167942", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:08.169365", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:08.169984", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 35s"}
- {"dt": "2025-04-04T18:11:08.179367", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.181923", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.184001", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.185650", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.187199", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.188776", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.189923", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.191605", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.192780", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.194657", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.195799", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.197596", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.198673", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.200470", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.201803", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.203719", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.204732", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.206350", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.207628", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.209228", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.210351", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.212160", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.213258", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.215033", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.217268", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.219894", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.221076", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.222778", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.224866", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.227482", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.228944", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.230126", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.231311", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.232658", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.235132", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.237038", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.238177", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.239381", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.240783", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.241966", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.243106", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.245110", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.247204", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.248828", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.251292", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.254238", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.257049", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.259937", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.261004", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.261689", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.262211", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.262672", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.263148", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.263720", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.264157", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.264636", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.265125", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.267481", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.267755", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.267842", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.268079", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.268271", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.268653", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.268714", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.269060", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.269152", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.269496", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.269601", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.269878", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.270032", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.270371", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.270469", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.270765", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.271063", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.271116", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.271354", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.271793", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.273508", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.274238", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.276127", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.276236", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.277244", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.279217", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.279286", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.280561", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.282214", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.283245", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.284903", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.284971", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.285244", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.286100", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.288269", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.288487", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.288914", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.289003", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.289170", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.289502", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.289626", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.290036", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.290140", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.290272", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.290610", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.290906", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.292203", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.293427", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.295381", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.296093", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.297268", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.299214", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.299804", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.301175", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.303176", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.304761", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.306203", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.306472", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.306790", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.307871", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.309450", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.310426", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.312095", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.313194", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.315092", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.315531", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.316557", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.318347", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.318602", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.319866", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.321467", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.322537", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.324483", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.324949", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.325352", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-04T18:11:08.326561", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-04T18:11:08.328825", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.330772", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.333477", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.334989", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.336541", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.338864", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.341532", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.344434", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.346107", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.347683", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.349679", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.352486", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.354946", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.357415", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.360288", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.362840", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.365509", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.367213", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.368722", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.371206", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.373904", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.376703", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.378457", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.379656", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.382165", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.384698", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.387303", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.389983", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.392515", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.394180", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.395103", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.397586", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.399242", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.400588", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.403178", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.405913", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.408818", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.411470", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.414022", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.415703", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.416866", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.419402", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.421843", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.424490", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.426315", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.427516", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.429580", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.432754", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.435322", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.437923", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.440727", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.442293", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.443512", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.445768", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.447316", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.448849", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.450964", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.453920", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.456846", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.458519", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.459717", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.462209", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.464340", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.465610", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.467779", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.470228", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-04T18:11:08.475780", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:08.476087", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:08.476169", "lvl": "target", "msg": "passed   199"}
- {"dt": "2025-04-04T18:11:08.476242", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:08.476311", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:08.476352", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:08.476389", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:08.508884", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-rHga5SsArH/LTP_forYutZjy as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:08.510086", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:08.510613", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:08.511280", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:08.511892", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-04T18:11:08.518655", "lvl": "target", "msg": "fork01.c:47: TPASS: correct child status returned 42"}
- {"dt": "2025-04-04T18:11:08.519391", "lvl": "target", "msg": "fork01.c:50: TPASS: child_pid == pid (277)"}
- {"dt": "2025-04-04T18:11:08.523266", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:08.523521", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:08.523596", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-04T18:11:08.523674", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:08.523765", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:08.523831", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:08.523901", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:08.535276", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:08.535779", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:08.536601", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:08.537097", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-04T18:11:08.540116", "lvl": "target", "msg": "time01.c:36: TPASS: time() returned value 1743790268"}
- {"dt": "2025-04-04T18:11:08.540413", "lvl": "target", "msg": "time01.c:38: TPASS: time() returned value 1743790268, stored value 1743790268 are same"}
- {"dt": "2025-04-04T18:11:08.541402", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:08.541638", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:08.541696", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-04T18:11:08.542091", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:08.542330", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:08.542567", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:08.542783", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:08.554828", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:08.555421", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:08.556191", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:08.556704", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-04T18:11:08.561251", "lvl": "target", "msg": "wait02.c:41: TPASS: wait() succeeded"}
- {"dt": "2025-04-04T18:11:08.563596", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:08.563844", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:08.563904", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-04T18:11:08.563957", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:08.564008", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:08.564056", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:08.564103", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:08.575311", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-rHga5SsArH/LTP_wri4jNnVx as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:08.576629", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:08.577185", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:08.577846", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:08.578334", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-04T18:11:09.006888", "lvl": "target", "msg": "write01.c:40: TPASS: write() passed"}
- {"dt": "2025-04-04T18:11:09.008454", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.008740", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:09.009020", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-04T18:11:09.009295", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:09.009561", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:09.009803", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:09.010243", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:09.079956", "lvl": "target", "msg": "symlink01    0  TINFO  :  Using /scratch/ltp-rHga5SsArH/LTP_sym80j5qm as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:09.080104", "lvl": "target", "msg": "symlink01    1  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-04T18:11:09.080279", "lvl": "target", "msg": "symlink01    2  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-04T18:11:09.080485", "lvl": "target", "msg": "symlink01    3  TPASS  :  Creation of symbolic link file and object file via symbolic link is ok"}
- {"dt": "2025-04-04T18:11:09.080650", "lvl": "target", "msg": "symlink01    4  TPASS  :  Creating an existing symbolic link file error is caught"}
- {"dt": "2025-04-04T18:11:09.080855", "lvl": "target", "msg": "symlink01    5  TPASS  :  Creating a symbolic link which exceeds maximum pathname error is caught"}
- {"dt": "2025-04-04T18:11:09.092807", "lvl": "target", "msg": "tst_buffers.c:57: TINFO: Test is using guarded buffers"}
- {"dt": "2025-04-04T18:11:09.095117", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-rHga5SsArH/LTP_sta4Wi3E2 as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:09.111790", "lvl": "target", "msg": "tst_device.c:147: TINFO: No free devices found"}
- {"dt": "2025-04-04T18:11:09.112184", "lvl": "target", "msg": "tst_device.c:360: TBROK: Failed to acquire device"}
- {"dt": "2025-04-04T18:11:09.112613", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.112873", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:09.112952", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-04T18:11:09.113186", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:09.113405", "lvl": "target", "msg": "broken   1"}
- {"dt": "2025-04-04T18:11:09.113624", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:09.114022", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:09.129342", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-rHga5SsArH/LTP_splGY5PEh as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-04T18:11:09.130397", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-04T18:11:09.130899", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64"}
- {"dt": "2025-04-04T18:11:09.131797", "lvl": "target", "msg": "tst_kconfig.c:71: TINFO: Couldn't locate kernel config!"}
- {"dt": "2025-04-04T18:11:09.132244", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-04T18:11:09.135714", "lvl": "target", "msg": "splice02.c:135: TINFO: writting 20 bytes"}
- {"dt": "2025-04-04T18:11:09.138043", "lvl": "target", "msg": "splice02.c:87: TINFO: checking file content"}
- {"dt": "2025-04-04T18:11:09.138988", "lvl": "target", "msg": "splice02.c:111: TPASS: splice() system call passed"}
- {"dt": "2025-04-04T18:11:09.143823", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.144102", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:09.144192", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-04T18:11:09.144263", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:09.144341", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:09.144420", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:09.144491", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:09.228063", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Running: shell_pipe01.sh "}
- {"dt": "2025-04-04T18:11:09.237140", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64 GNU/Linux"}
- {"dt": "2025-04-04T18:11:09.282576", "lvl": "target", "msg": "shell_pipe01 1 TINFO: timeout per run is 0h 25m 0s"}
- {"dt": "2025-04-04T18:11:09.310899", "lvl": "target", "msg": "shell_pipe01 1 TINFO: expecting SUCCESS string passed from stdin"}
- {"dt": "2025-04-04T18:11:09.324537", "lvl": "target", "msg": "shell_pipe01 1 TPASS: [ SUCCESS = SUCCESS ] passed as expected"}
- {"dt": "2025-04-04T18:11:09.341092", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.341185", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:09.341463", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-04T18:11:09.341550", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:09.341625", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:09.341696", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-04T18:11:09.341807", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:09.445240", "lvl": "target", "msg": "macsec02 1 TINFO: Running: macsec02.sh "}
- {"dt": "2025-04-04T18:11:09.456229", "lvl": "target", "msg": "macsec02 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Fri Apr  4 19:29:47 CEST 2025 aarch64 GNU/Linux"}
- {"dt": "2025-04-04T18:11:09.605163", "lvl": "target", "msg": "macsec02 1 TCONF: veth driver not available"}
- {"dt": "2025-04-04T18:11:09.612851", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.613132", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-04T18:11:09.613201", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-04T18:11:09.613254", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-04T18:11:09.613312", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-04T18:11:09.613370", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-04-04T18:11:09.613423", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-04T18:11:09.620790", "lvl": "target", "msg": "INFO: ltp-pan reported some tests FAIL"}
- {"dt": "2025-04-04T18:11:09.621265", "lvl": "target", "msg": "LTP Version: 20250130"}
- {"dt": "2025-04-04T18:11:09.629282", "lvl": "target", "msg": "INFO: Test end time: Fri Apr  4 18:11:09 UTC 2025"}
- {"dt": "2025-04-04T18:11:09.634661", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.634912", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-04T18:11:09.634984", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.635060", "lvl": "target", "msg": "            Done executing testcases."}
- {"dt": "2025-04-04T18:11:09.635118", "lvl": "target", "msg": "            LTP Version:  20250130"}
- {"dt": "2025-04-04T18:11:09.635234", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-04T18:11:09.635313", "lvl": "target", "msg": ""}
- {"dt": "2025-04-04T18:11:09.637025", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-04T18:11:09.637161", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-04T18:11:09.637417", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-04T18:11:09.637547", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-04T18:11:09.649966", "lvl": "target", "msg": "++ echo 1"}
- {"dt": "2025-04-04T18:11:09.656392", "lvl": "target", "msg": "+ ret_val=1"}
- {"dt": "2025-04-04T18:11:09.656459", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-04T18:11:09.656697", "lvl": "target", "msg": "+ return 1"}
- {"dt": "2025-04-04T18:11:09.657281", "lvl": "target", "msg": "+ parse_ltp_output /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-04T18:11:09.660308", "lvl": "target", "msg": "+ grep -E 'PASS|FAIL|CONF' /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-04T18:11:09.662146", "lvl": "target", "msg": "+ sed 's/PASS/pass/; s/FAIL/fail/; s/CONF/skip/'"}
- {"dt": "2025-04-04T18:11:09.668853", "lvl": "target", "msg": "+ awk '{print $1\" \"$2}'"}
- {"dt": "2025-04-04T18:11:09.677822", "lvl": "target", "msg": "+ rm -rf /scratch"}
- {"dt": "2025-04-04T18:11:09.684567", "lvl": "target", "msg": "+ rm -rf alltests"}
- {"dt": "2025-04-04T18:11:09.693171", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-04-04T18:11:09.722334", "lvl": "target", "msg": "WARNING: no log file found for access01 at ./output/access01.log"}
- {"dt": "2025-04-04T18:11:09.741795", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=access01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.742126", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=access01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.742230", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "access01", "result": "pass", "starttc": ! "967", "endtc": ! "967"}}
- {"dt": "2025-04-04T18:11:09.753998", "lvl": "target", "msg": "WARNING: no log file found for chdir01 at ./output/chdir01.log"}
- {"dt": "2025-04-04T18:11:09.773015", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=chdir01 RESULT=skip>"}
- {"dt": "2025-04-04T18:11:09.773303", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=chdir01 RESULT=skip"}
- {"dt": "2025-04-04T18:11:09.773396", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "chdir01", "result": "skip", "starttc": ! "971", "endtc": ! "971"}}
- {"dt": "2025-04-04T18:11:09.784968", "lvl": "target", "msg": "WARNING: no log file found for fork01 at ./output/fork01.log"}
- {"dt": "2025-04-04T18:11:09.803590", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=fork01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.803861", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=fork01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.803958", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "fork01", "result": "pass", "starttc": ! "975", "endtc": ! "975"}}
- {"dt": "2025-04-04T18:11:09.814736", "lvl": "target", "msg": "WARNING: no log file found for time01 at ./output/time01.log"}
- {"dt": "2025-04-04T18:11:09.834376", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=time01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.834645", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=time01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.834731", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "time01", "result": "pass", "starttc": ! "979", "endtc": ! "979"}}
- {"dt": "2025-04-04T18:11:09.845519", "lvl": "target", "msg": "WARNING: no log file found for wait02 at ./output/wait02.log"}
- {"dt": "2025-04-04T18:11:09.863388", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=wait02 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.863655", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=wait02 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.863746", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "wait02", "result": "pass", "starttc": ! "983", "endtc": ! "983"}}
- {"dt": "2025-04-04T18:11:09.874871", "lvl": "target", "msg": "WARNING: no log file found for write01 at ./output/write01.log"}
- {"dt": "2025-04-04T18:11:09.892723", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=write01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.892989", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=write01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.893075", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "write01", "result": "pass", "starttc": ! "987", "endtc": ! "987"}}
- {"dt": "2025-04-04T18:11:09.904489", "lvl": "target", "msg": "WARNING: no log file found for symlink01 at ./output/symlink01.log"}
- {"dt": "2025-04-04T18:11:09.922325", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=symlink01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.922600", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=symlink01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.922704", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "symlink01", "result": "pass", "starttc": ! "991", "endtc": ! "991"}}
- {"dt": "2025-04-04T18:11:09.933449", "lvl": "target", "msg": "WARNING: no log file found for stat04 at ./output/stat04.log"}
- {"dt": "2025-04-04T18:11:09.950620", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=stat04 RESULT=fail>"}
- {"dt": "2025-04-04T18:11:09.950885", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=stat04 RESULT=fail"}
- {"dt": "2025-04-04T18:11:09.950978", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "stat04", "result": "fail", "starttc": ! "995", "endtc": ! "995"}}
- {"dt": "2025-04-04T18:11:09.961992", "lvl": "target", "msg": "WARNING: no log file found for splice02 at ./output/splice02.log"}
- {"dt": "2025-04-04T18:11:09.980309", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=splice02 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:09.980575", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=splice02 RESULT=pass"}
- {"dt": "2025-04-04T18:11:09.980655", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "splice02", "result": "pass", "starttc": ! "999", "endtc": ! "999"}}
- {"dt": "2025-04-04T18:11:09.991793", "lvl": "target", "msg": "WARNING: no log file found for df01_sh at ./output/df01_sh.log"}
- {"dt": "2025-04-04T18:11:10.009158", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=df01_sh RESULT=skip>"}
- {"dt": "2025-04-04T18:11:10.009450", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=df01_sh RESULT=skip"}
- {"dt": "2025-04-04T18:11:10.009551", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "df01_sh", "result": "skip", "starttc": ! "1003", "endtc": ! "1003"}}
- {"dt": "2025-04-04T18:11:10.020384", "lvl": "target", "msg": "WARNING: no log file found for shell_test01 at ./output/shell_test01.log"}
- {"dt": "2025-04-04T18:11:10.039031", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shell_test01 RESULT=pass>"}
- {"dt": "2025-04-04T18:11:10.039303", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shell_test01 RESULT=pass"}
- {"dt": "2025-04-04T18:11:10.039400", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "shell_test01", "result": "pass", "starttc": ! "1007", "endtc": ! "1007"}}
- {"dt": "2025-04-04T18:11:10.050302", "lvl": "target", "msg": "WARNING: no log file found for ping602 at ./output/ping602.log"}
- {"dt": "2025-04-04T18:11:10.068329", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping602 RESULT=skip>"}
- {"dt": "2025-04-04T18:11:10.068594", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=ping602 RESULT=skip"}
- {"dt": "2025-04-04T18:11:10.068737", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "ping602", "result": "skip", "starttc": ! "1011", "endtc": ! "1011"}}
- {"dt": "2025-04-04T18:11:10.080092", "lvl": "target", "msg": "WARNING: no log file found for macsec02 at ./output/macsec02.log"}
- {"dt": "2025-04-04T18:11:10.098176", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=macsec02 RESULT=skip>"}
- {"dt": "2025-04-04T18:11:10.098423", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=macsec02 RESULT=skip"}
- {"dt": "2025-04-04T18:11:10.098491", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "macsec02", "result": "skip", "starttc": ! "1015", "endtc": ! "1015"}}
- {"dt": "2025-04-04T18:11:10.102538", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-04T18:11:10.103150", "lvl": "target", "msg": "<8><LAVA_SIGNAL_ENDRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-04T18:11:10.103534", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-04T18:11:10.103646", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-04-04T18:11:10.103740", "lvl": "info", "msg": "Ending test lava.0_ltp-smoke (1_1.1.3.1), duration 2.59"}
- {"dt": "2025-04-04T18:11:10.103839", "lvl": "results", "msg": {"definition": "lava", "case": "0_ltp-smoke", "uuid": "1_1.1.3.1", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "path": "automated/linux/ltp/ltp.yaml", "duration": "2.59", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-04-04T18:11:10.107782", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-04-04T18:11:10.108058", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-04-04T18:11:10.108223", "lvl": "debug", "msg": "access01: pass\nchdir01: skip\nfork01: pass\ntime01: pass\nwait02: pass\nwrite01: pass\nsymlink01: pass\nstat04: fail\nsplice02: pass\ndf01_sh: skip\nshell_test01: pass\nping602: skip\nmacsec02: skip\n"}
- {"dt": "2025-04-04T18:11:10.108318", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:03) [common]"}
- {"dt": "2025-04-04T18:11:10.108396", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:03) [common]"}
- {"dt": "2025-04-04T18:11:10.108477", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:11:10.108560", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-04-04T18:11:10.108639", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-04-04T18:11:10.108710", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-04-04T18:11:10.108846", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-04T18:11:10.110477", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-04T18:11:10.110713", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-04T18:11:11.110176", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-04-04T18:11:11.110337", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-04-04T18:11:11.210879", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-04-04T18:11:11.211305", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-04T18:11:11.211404", "lvl": "target", "msg": "root@tuxtest:~# poweroff"}
- {"dt": "2025-04-04T18:11:11.311676", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-04-04T18:11:11.311765", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-04T18:11:11.311849", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-04-04T18:11:11.311938", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-04-04T18:11:11.312042", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/url-repo-action-rusjrbvq/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-04T18:11:11.318909", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/kernel/Image"}
- {"dt": "2025-04-04T18:11:11.320636", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1/deployimages-k3mbtc4h/rootfs/rootfs.ext2"}
- {"dt": "2025-04-04T18:11:11.484083", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-x7uqr5cc-1"}
- {"dt": "2025-04-04T18:11:11.519198", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-04-04T18:11:11.519290", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
