- {"dt": "2023-03-22T11:49:20.341303", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-22T11:49:20.341797", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-22T11:49:20.342126", "lvl": "info", "msg": "Start time: 2023-03-22 11:49:20.342107+00:00 (UTC)"}
- {"dt": "2023-03-22T11:49:20.345690", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28567/2/qemuarm64/Image exists"}
- {"dt": "2023-03-22T11:49:20.546062", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28567/2/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-22T11:49:20.774578", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-22T11:49:20.776132", "lvl": "info", "msg": "validate duration: 0.43"}
- {"dt": "2023-03-22T11:49:20.776423", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-22T11:49:20.776927", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.777241", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.777770", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb"}
- {"dt": "2023-03-22T11:49:20.778192", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin"}
- {"dt": "2023-03-22T11:49:20.778540", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/tests"}
- {"dt": "2023-03-22T11:49:20.778874", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/results"}
- {"dt": "2023-03-22T11:49:20.779221", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-add-keys"}
- {"dt": "2023-03-22T11:49:20.779683", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-add-sources"}
- {"dt": "2023-03-22T11:49:20.780522", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-background-process-start"}
- {"dt": "2023-03-22T11:49:20.780964", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-background-process-stop"}
- {"dt": "2023-03-22T11:49:20.781379", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-common-functions"}
- {"dt": "2023-03-22T11:49:20.781796", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-echo-ipv4"}
- {"dt": "2023-03-22T11:49:20.782231", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-install-packages"}
- {"dt": "2023-03-22T11:49:20.782660", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-installed-packages"}
- {"dt": "2023-03-22T11:49:20.783106", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-os-build"}
- {"dt": "2023-03-22T11:49:20.783522", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-probe-channel"}
- {"dt": "2023-03-22T11:49:20.783942", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-probe-ip"}
- {"dt": "2023-03-22T11:49:20.784374", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-target-ip"}
- {"dt": "2023-03-22T11:49:20.784840", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-target-mac"}
- {"dt": "2023-03-22T11:49:20.785261", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-target-storage"}
- {"dt": "2023-03-22T11:49:20.785678", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-case"}
- {"dt": "2023-03-22T11:49:20.786092", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-event"}
- {"dt": "2023-03-22T11:49:20.786502", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-feedback"}
- {"dt": "2023-03-22T11:49:20.786909", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-raise"}
- {"dt": "2023-03-22T11:49:20.787330", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-reference"}
- {"dt": "2023-03-22T11:49:20.787747", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-runner"}
- {"dt": "2023-03-22T11:49:20.788153", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-set"}
- {"dt": "2023-03-22T11:49:20.788579", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-test-shell"}
- {"dt": "2023-03-22T11:49:20.788999", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-22T11:49:20.789471", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-22T11:49:20.789866", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/environment"}
- {"dt": "2023-03-22T11:49:20.790197", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-22T11:49:20.790441", "lvl": "debug", "msg": "- LAVA_JOB_ID=32329"}
- {"dt": "2023-03-22T11:49:20.790670", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.22.0.2"}
- {"dt": "2023-03-22T11:49:20.790994", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.791233", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-22T11:49:20.791499", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.791776", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.791999", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-22T11:49:20.792250", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.792551", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.792941", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-22T11:49:20.793271", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.793531", "lvl": "debug", "msg": "Using /lava-32329 at stage 0"}
- {"dt": "2023-03-22T11:49:20.794584", "lvl": "debug", "msg": "uuid=32329_1.1.3.1 testdef=None"}
- {"dt": "2023-03-22T11:49:20.794879", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.795158", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.796817", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.797067", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32329_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:20.797625", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.799662", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.799914", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "skipped test-install-overlay": "32329_1.1.3.1", "uuid": "32329_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:20.800513", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.865855", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/0/tests/0_fixup-weston-watchdog test_uuid 32329_1.1.3.1"}
- {"dt": "2023-03-22T11:49:20.866357", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:20.866606", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.07", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32329_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:20.867195", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-22T11:49:20.867446", "lvl": "debug", "msg": "Using /lava-32329 at stage 1"}
- {"dt": "2023-03-22T11:49:20.867767", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-22T11:49:20.868030", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-22T11:49:22.617623", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/1_ci-busybox"}
- {"dt": "2023-03-22T11:49:22.620732", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-22T11:49:22.621932", "lvl": "debug", "msg": "uuid=32329_1.1.3.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-22T11:49:22.622358", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-22T11:49:22.622626", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.76", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:22.623172", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-22T11:49:22.625212", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:22.625460", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:22.626045", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-22T11:49:22.628744", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:22.628998", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32329_1.1.3.5", "uuid": "32329_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:22.629619", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-22T11:49:22.634420", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/1_ci-busybox test_uuid 32329_1.1.3.5"}
- {"dt": "2023-03-22T11:49:22.634930", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:22.635179", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/1_ci-busybox/run.sh", "from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:22.635781", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:58) [common]"}
- {"dt": "2023-03-22T11:49:22.636035", "lvl": "debug", "msg": "Using /lava-32329 at stage 1"}
- {"dt": "2023-03-22T11:49:22.636393", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-22T11:49:22.636676", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-22T11:49:24.241471", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-22T11:49:24.244451", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-22T11:49:24.245524", "lvl": "debug", "msg": "uuid=32329_1.1.3.9 testdef=None"}
- {"dt": "2023-03-22T11:49:24.245920", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-22T11:49:24.246188", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.61", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:24.246733", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-22T11:49:24.248563", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:24.248815", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:24.249399", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-22T11:49:24.251734", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:24.251990", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32329_1.1.3.9", "uuid": "32329_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:24.252618", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-22T11:49:24.260399", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/2_ci-smoke-tests-basic test_uuid 32329_1.1.3.9"}
- {"dt": "2023-03-22T11:49:24.261386", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:24.261643", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/2_ci-smoke-tests-basic/run.sh", "from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:24.262242", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-03-22T11:49:24.262498", "lvl": "debug", "msg": "Using /lava-32329 at stage 1"}
- {"dt": "2023-03-22T11:49:24.262853", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-22T11:49:24.263120", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-22T11:49:25.840771", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/3_ci-service-check"}
- {"dt": "2023-03-22T11:49:25.843760", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-22T11:49:25.844866", "lvl": "debug", "msg": "uuid=32329_1.1.3.13 testdef=None"}
- {"dt": "2023-03-22T11:49:25.845251", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-22T11:49:25.845508", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.58", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:25.846047", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-22T11:49:25.847921", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:25.848165", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:25.848760", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-22T11:49:25.851190", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:25.851442", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32329_1.1.3.13", "uuid": "32329_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:25.852056", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-22T11:49:25.873893", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/3_ci-service-check test_uuid 32329_1.1.3.13"}
- {"dt": "2023-03-22T11:49:25.874164", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-22T11:49:25.874384", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-22T11:49:25.874834", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:25.875071", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1/tests/3_ci-service-check/run.sh", "from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:25.875664", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-22T11:49:25.875910", "lvl": "debug", "msg": "Using /lava-32329 at stage 2"}
- {"dt": "2023-03-22T11:49:25.876260", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-22T11:49:25.876537", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/2/tests/4_screenshooter'"}
- {"dt": "2023-03-22T11:49:27.497671", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-22T11:49:27.499128", "lvl": "debug", "msg": "uuid=32329_1.1.3.17 testdef=None"}
- {"dt": "2023-03-22T11:49:27.499571", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-22T11:49:27.499857", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.62", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:27.500435", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-22T11:49:27.502113", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:27.502373", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:27.502991", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-22T11:49:27.505067", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:27.505342", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32329_1.1.3.17", "uuid": "32329_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:27.505982", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-22T11:49:27.519505", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/2/tests/4_screenshooter test_uuid 32329_1.1.3.17"}
- {"dt": "2023-03-22T11:49:27.520051", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:27.520308", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/2/tests/4_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:27.520945", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-22T11:49:27.521214", "lvl": "debug", "msg": "Using /lava-32329 at stage 3"}
- {"dt": "2023-03-22T11:49:27.521589", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-22T11:49:27.521870", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-22T11:49:29.133974", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-22T11:49:29.136961", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-22T11:49:29.138135", "lvl": "debug", "msg": "uuid=32329_1.1.3.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-22T11:49:29.138546", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-22T11:49:29.138813", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.62", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:29.139364", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-22T11:49:29.141362", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:29.141610", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:29.142199", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-22T11:49:29.144806", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:29.145059", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32329_1.1.3.21", "uuid": "32329_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:29.145671", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-22T11:49:29.173506", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3/tests/5_ci-dumpjournal test_uuid 32329_1.1.3.21"}
- {"dt": "2023-03-22T11:49:29.174019", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:29.174267", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3/tests/5_ci-dumpjournal/run.sh", "from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32329_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:29.174818", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-22T11:49:29.175042", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/0 for stage 0"}
- {"dt": "2023-03-22T11:49:29.175348", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-22T11:49:29.175636", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/1 for stage 1"}
- {"dt": "2023-03-22T11:49:29.175940", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-22T11:49:29.176163", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-22T11:49:29.176374", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-22T11:49:29.176632", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/2 for stage 2"}
- {"dt": "2023-03-22T11:49:29.176931", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-22T11:49:29.177183", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32329/lava-overlay-v27p5ubb/lava-32329/3 for stage 3"}
- {"dt": "2023-03-22T11:49:29.177479", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-22T11:49:29.177794", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:08) [common]"}
- {"dt": "2023-03-22T11:49:29.178079", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-22T11:49:29.422742", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:29.423191", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-22T11:49:29.423487", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:29.423815", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2023-03-22T11:49:29.424109", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:51) [common]"}
- {"dt": "2023-03-22T11:49:29.424369", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32329/compress-overlay-hd4e3miq/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-22T11:49:33.792657", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-22T11:49:33.793451", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "4.37", "extra": {"success": "ba332b12-a6f6-4c22-9601-b5b4c018a99a"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:33.794014", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:47) [common]"}
- {"dt": "2023-03-22T11:49:33.794317", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:49:33.794617", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:47) [common]"}
- {"dt": "2023-03-22T11:49:33.794926", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:47) [common]"}
- {"dt": "2023-03-22T11:49:33.795418", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28567/2/qemuarm64/Image"}
- {"dt": "2023-03-22T11:49:33.795654", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/kernel/Image"}
- {"dt": "2023-03-22T11:49:33.795876", "lvl": "debug", "msg": "total size: 21885440 (20MB)"}
- {"dt": "2023-03-22T11:49:33.796091", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-22T11:49:34.100506", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-22T11:49:34.877248", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-22T11:49:35.366594", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-22T11:49:35.850475", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-22T11:49:36.239503", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-22T11:49:36.536554", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-22T11:49:36.912341", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-22T11:49:37.203224", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-22T11:49:37.419056", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-22T11:49:37.706744", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-22T11:49:37.904224", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-22T11:49:38.168712", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-22T11:49:38.369181", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-22T11:49:38.564004", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-22T11:49:38.721799", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-22T11:49:38.877550", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-22T11:49:39.065003", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-22T11:49:39.235708", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-22T11:49:39.360264", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-22T11:49:39.522177", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-22T11:49:39.643322", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-22T11:49:39.644042", "lvl": "info", "msg": "20MB downloaded in 5.85s (3.57MB/s)"}
- {"dt": "2023-03-22T11:49:39.644541", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:06) [common]"}
- {"dt": "2023-03-22T11:49:39.644802", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "5.85", "extra": {"label": "kernel", "md5sum": "48303aec3e8019f21bc725444b4ed6e6", "sha256sum": "b50dc761bd62f83daea4b5718c1ba0bbf9db024fb619c452187ded96b36658c4", "sha512sum": "b939292618c842586b14d01a7c570197e065e6fd4e85c04909e57e35ed3e7f013a4755a32705c8f77401a25790937496411c11eaf5711d1d1c3235c119087ba9", "size": ! "21885440"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:49:39.645418", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:06) [common]"}
- {"dt": "2023-03-22T11:49:39.645714", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:41) [common]"}
- {"dt": "2023-03-22T11:49:39.646010", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:41) [common]"}
- {"dt": "2023-03-22T11:49:39.646423", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28567/2/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-22T11:49:39.646664", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-22T11:49:39.646885", "lvl": "debug", "msg": "total size: 477127344 (455MB)"}
- {"dt": "2023-03-22T11:49:39.647112", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-22T11:49:39.949814", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-22T11:49:44.527736", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-22T11:49:48.164923", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-22T11:49:52.031550", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-22T11:49:55.931789", "lvl": "debug", "msg": "progress  20% (91MB)"}
- {"dt": "2023-03-22T11:50:00.082013", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-22T11:50:05.748595", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-22T11:50:13.183848", "lvl": "debug", "msg": "progress  35% (159MB)"}
- {"dt": "2023-03-22T11:50:20.833045", "lvl": "debug", "msg": "progress  40% (182MB)"}
- {"dt": "2023-03-22T11:50:27.742299", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-22T11:50:36.213045", "lvl": "debug", "msg": "progress  50% (227MB)"}
- {"dt": "2023-03-22T11:50:44.098648", "lvl": "debug", "msg": "progress  55% (250MB)"}
- {"dt": "2023-03-22T11:50:49.527309", "lvl": "debug", "msg": "progress  60% (273MB)"}
- {"dt": "2023-03-22T11:50:53.889371", "lvl": "debug", "msg": "progress  65% (295MB)"}
- {"dt": "2023-03-22T11:50:57.602135", "lvl": "debug", "msg": "progress  70% (318MB)"}
- {"dt": "2023-03-22T11:51:00.703020", "lvl": "debug", "msg": "progress  75% (341MB)"}
- {"dt": "2023-03-22T11:51:04.177715", "lvl": "debug", "msg": "progress  80% (364MB)"}
- {"dt": "2023-03-22T11:51:07.586550", "lvl": "debug", "msg": "progress  85% (386MB)"}
- {"dt": "2023-03-22T11:51:10.871034", "lvl": "debug", "msg": "progress  90% (409MB)"}
- {"dt": "2023-03-22T11:51:14.034761", "lvl": "debug", "msg": "progress  95% (432MB)"}
- {"dt": "2023-03-22T11:51:19.197854", "lvl": "debug", "msg": "progress 100% (455MB)"}
- {"dt": "2023-03-22T11:51:21.828676", "lvl": "info", "msg": "455MB downloaded in 102.18s (4.45MB/s)"}
- {"dt": "2023-03-22T11:51:21.829989", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:42) [common]"}
- {"dt": "2023-03-22T11:51:21.830325", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "102.18", "extra": {"label": "rootvd", "md5sum": "037af9b5663f90ab2dc8cda52f98d086", "sha256sum": "f48c056fb9c280a7b0fcda69705f3f7f01523f500f8e8a464abf5c34d0e24c44", "sha512sum": "b33c38382f7360cfe58696888620b2fea5d243dda6a164d6c81165510db7f223b9b7f184b0b5966df0a95019e9cc09e69f859df7870f857e96290504c021e53e", "size": ! "477127344"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:51:21.831093", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:42) [common]"}
- {"dt": "2023-03-22T11:51:21.831428", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:01) [common]"}
- {"dt": "2023-03-22T11:51:21.831792", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-22T11:51:21.832166", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-22T11:51:21.832491", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-22T11:51:21.833300", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-22T11:51:21.833950", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu cortex-a57 -machine virt -nographic -net nic,model=virtio,macaddr=52:54:00:12:34:58 -net user -m 4096 -monitor none -smp 2 -soundhw hda -device usb-ehci -device virtio-rng-pci -device VGA,vgamem_mb=128,edid=on -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc unix:/tmp/vnc-AGL-qemuarm64-828920 -kernel /var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/kernel/Image -append \"console=ttyAMA0,115200 root=/dev/vda verbose mem=4096M systemd.log_color=false \" -drive format=raw,file=/var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32329/apply-overlay-guest-d2bnpfla/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-22T11:51:22.720493", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-22T11:51:22.721416", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-22T11:51:22.721700", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.89", "extra": {"host_arch": "amd64", "job_arch": "aarch64", "qemu_version": "1:7.2+dfsg-1~bpo11+2"}, "level": "2.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-22T11:51:22.722319", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-22T11:51:22.722631", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-22T11:51:22.722969", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-22T11:51:22.723225", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-22T11:51:22.744394", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-22T11:51:22.760862", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-22T11:51:22.761147", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:51:22.761405", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.04", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-22T11:51:22.761870", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-22T11:51:22.762140", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-22T11:51:22.762373", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-22T11:51:22.762611", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.93", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-22T11:51:22.763176", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-22T11:51:22.763589", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/kernel"}
- {"dt": "2023-03-22T11:51:22.768415", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32329/deployimages-wu1mbzas/rootvd"}
- {"dt": "2023-03-22T11:51:23.286673", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-22T11:51:23.287087", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:51:23.287407", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-22T11:51:23.288052", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-22T11:51:23.288426", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-22T11:51:23.389444", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-22T11:51:23.389847", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32329"}
- {"dt": "2023-03-22T11:51:23.410056", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-22T11:51:23.410335", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
