- {"dt": "2023-03-15T17:43:06.703609", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-15T17:43:06.704108", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-15T17:43:06.704436", "lvl": "info", "msg": "Start time: 2023-03-15 17:43:06.704417+00:00 (UTC)"}
- {"dt": "2023-03-15T17:43:06.708039", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28569/1/qemuarm64/Image exists"}
- {"dt": "2023-03-15T17:43:06.909367", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28569/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-15T17:43:07.137901", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-15T17:43:07.139486", "lvl": "info", "msg": "validate duration: 0.44"}
- {"dt": "2023-03-15T17:43:07.139777", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-15T17:43:07.140263", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.140589", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.141148", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3"}
- {"dt": "2023-03-15T17:43:07.141566", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin"}
- {"dt": "2023-03-15T17:43:07.141911", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/tests"}
- {"dt": "2023-03-15T17:43:07.142238", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/results"}
- {"dt": "2023-03-15T17:43:07.142598", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-add-keys"}
- {"dt": "2023-03-15T17:43:07.143068", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-add-sources"}
- {"dt": "2023-03-15T17:43:07.143501", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-background-process-start"}
- {"dt": "2023-03-15T17:43:07.143942", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-background-process-stop"}
- {"dt": "2023-03-15T17:43:07.144360", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-common-functions"}
- {"dt": "2023-03-15T17:43:07.144790", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-echo-ipv4"}
- {"dt": "2023-03-15T17:43:07.145219", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-install-packages"}
- {"dt": "2023-03-15T17:43:07.145641", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-installed-packages"}
- {"dt": "2023-03-15T17:43:07.146059", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-os-build"}
- {"dt": "2023-03-15T17:43:07.146480", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-probe-channel"}
- {"dt": "2023-03-15T17:43:07.146910", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-probe-ip"}
- {"dt": "2023-03-15T17:43:07.147343", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-target-ip"}
- {"dt": "2023-03-15T17:43:07.147788", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-target-mac"}
- {"dt": "2023-03-15T17:43:07.148217", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-target-storage"}
- {"dt": "2023-03-15T17:43:07.148650", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-case"}
- {"dt": "2023-03-15T17:43:07.149069", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-event"}
- {"dt": "2023-03-15T17:43:07.149480", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-feedback"}
- {"dt": "2023-03-15T17:43:07.149888", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-raise"}
- {"dt": "2023-03-15T17:43:07.150308", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-reference"}
- {"dt": "2023-03-15T17:43:07.150721", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-runner"}
- {"dt": "2023-03-15T17:43:07.151128", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-set"}
- {"dt": "2023-03-15T17:43:07.151542", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-test-shell"}
- {"dt": "2023-03-15T17:43:07.151959", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-15T17:43:07.152444", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-15T17:43:07.152862", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/environment"}
- {"dt": "2023-03-15T17:43:07.153193", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-15T17:43:07.153439", "lvl": "debug", "msg": "- LAVA_JOB_ID=32269"}
- {"dt": "2023-03-15T17:43:07.153666", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-15T17:43:07.153992", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.154229", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-15T17:43:07.154496", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.154779", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.155007", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-15T17:43:07.155263", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.155543", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.155942", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-15T17:43:07.156266", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.156543", "lvl": "debug", "msg": "Using /lava-32269 at stage 0"}
- {"dt": "2023-03-15T17:43:07.157595", "lvl": "debug", "msg": "uuid=32269_1.1.3.1 testdef=None"}
- {"dt": "2023-03-15T17:43:07.157896", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.158184", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.159845", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.160104", "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": "32269_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:07.160685", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.162751", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.163012", "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": "32269_1.1.3.1", "uuid": "32269_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:07.163596", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.165593", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/0/tests/0_fixup-weston-watchdog test_uuid 32269_1.1.3.1"}
- {"dt": "2023-03-15T17:43:07.166100", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:07.166352", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32269_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:07.166947", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T17:43:07.167197", "lvl": "debug", "msg": "Using /lava-32269 at stage 1"}
- {"dt": "2023-03-15T17:43:07.167520", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T17:43:07.167796", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-15T17:43:08.569203", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/1_ci-busybox"}
- {"dt": "2023-03-15T17:43:08.572284", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-15T17:43:08.573513", "lvl": "debug", "msg": "uuid=32269_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-15T17:43:08.573937", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:43:08.574212", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.41", "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-15T17:43:08.574770", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-03-15T17:43:08.576858", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:08.577118", "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": "32269_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:08.577716", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-03-15T17:43:08.580503", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:08.580766", "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": "32269_1.1.3.5", "uuid": "32269_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:08.581394", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-03-15T17:43:08.584059", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/1_ci-busybox test_uuid 32269_1.1.3.5"}
- {"dt": "2023-03-15T17:43:08.584600", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:08.584855", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/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": "32269_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:08.585469", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:59) [common]"}
- {"dt": "2023-03-15T17:43:08.585728", "lvl": "debug", "msg": "Using /lava-32269 at stage 1"}
- {"dt": "2023-03-15T17:43:08.586085", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T17:43:08.586351", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-15T17:43:09.978863", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T17:43:09.981822", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-15T17:43:09.982853", "lvl": "debug", "msg": "uuid=32269_1.1.3.9 testdef=None"}
- {"dt": "2023-03-15T17:43:09.983241", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:43:09.983510", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.40", "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-15T17:43:09.984068", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T17:43:09.985923", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:09.986184", "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": "32269_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:09.986777", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T17:43:09.989199", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:09.989465", "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": "32269_1.1.3.9", "uuid": "32269_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:09.990102", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T17:43:09.992432", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/2_ci-smoke-tests-basic test_uuid 32269_1.1.3.9"}
- {"dt": "2023-03-15T17:43:09.993420", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:09.993686", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/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": "32269_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:09.994298", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T17:43:09.994559", "lvl": "debug", "msg": "Using /lava-32269 at stage 1"}
- {"dt": "2023-03-15T17:43:09.994917", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T17:43:09.995188", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-15T17:43:11.397229", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/3_ci-service-check"}
- {"dt": "2023-03-15T17:43:11.400132", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-15T17:43:11.401218", "lvl": "debug", "msg": "uuid=32269_1.1.3.13 testdef=None"}
- {"dt": "2023-03-15T17:43:11.401605", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:43:11.401866", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.41", "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-15T17:43:11.402420", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T17:43:11.404333", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:11.404614", "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": "32269_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:11.405212", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T17:43:11.407695", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:11.407964", "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": "32269_1.1.3.13", "uuid": "32269_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:11.408622", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T17:43:11.411035", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1/tests/3_ci-service-check test_uuid 32269_1.1.3.13"}
- {"dt": "2023-03-15T17:43:11.411314", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-15T17:43:11.411542", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-15T17:43:11.411999", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:11.412246", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/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": "32269_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:11.412872", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T17:43:11.413130", "lvl": "debug", "msg": "Using /lava-32269 at stage 2"}
- {"dt": "2023-03-15T17:43:11.413485", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T17:43:11.413751", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/2/tests/4_screenshooter'"}
- {"dt": "2023-03-15T17:43:12.790890", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-15T17:43:12.792193", "lvl": "debug", "msg": "uuid=32269_1.1.3.17 testdef=None"}
- {"dt": "2023-03-15T17:43:12.792598", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:43:12.792868", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.38", "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-15T17:43:12.793427", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T17:43:12.795026", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:12.795278", "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": "32269_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:12.795875", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T17:43:12.797909", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:12.798173", "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": "32269_1.1.3.17", "uuid": "32269_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:12.798791", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T17:43:12.800746", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/2/tests/4_screenshooter test_uuid 32269_1.1.3.17"}
- {"dt": "2023-03-15T17:43:12.801260", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:12.801509", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/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": "32269_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:12.802111", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T17:43:12.802381", "lvl": "debug", "msg": "Using /lava-32269 at stage 3"}
- {"dt": "2023-03-15T17:43:12.802730", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T17:43:12.802994", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-15T17:43:14.210907", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-15T17:43:14.213879", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-15T17:43:14.215050", "lvl": "debug", "msg": "uuid=32269_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-15T17:43:14.215464", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:43:14.215732", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.41", "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-15T17:43:14.216297", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.218325", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:14.218585", "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": "32269_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:14.219179", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.221859", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:14.222121", "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": "32269_1.1.3.21", "uuid": "32269_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:14.222729", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.225284", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/3/tests/5_ci-dumpjournal test_uuid 32269_1.1.3.21"}
- {"dt": "2023-03-15T17:43:14.225801", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:14.226053", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/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": "32269_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:14.226614", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-15T17:43:14.226843", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/0 for stage 0"}
- {"dt": "2023-03-15T17:43:14.227157", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-15T17:43:14.227446", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/1 for stage 1"}
- {"dt": "2023-03-15T17:43:14.227756", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-15T17:43:14.227989", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T17:43:14.228203", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-15T17:43:14.228451", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/2 for stage 2"}
- {"dt": "2023-03-15T17:43:14.228776", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-15T17:43:14.229040", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32269/lava-overlay-zkt70ra3/lava-32269/3 for stage 3"}
- {"dt": "2023-03-15T17:43:14.229344", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-15T17:43:14.229670", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:07) [common]"}
- {"dt": "2023-03-15T17:43:14.229974", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.470234", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:14.470570", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.470864", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:14.471173", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:07) [common]"}
- {"dt": "2023-03-15T17:43:14.471466", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T17:43:14.471717", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32269/compress-overlay-zusb2_j6/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-15T17:43:18.297154", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-15T17:43:18.297925", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "3.83", "extra": {"success": "0690826f-a091-41aa-802a-e97f275f6a31"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:18.298486", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T17:43:18.298788", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:43:18.299092", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T17:43:18.299394", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T17:43:18.299876", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28569/1/qemuarm64/Image"}
- {"dt": "2023-03-15T17:43:18.300110", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32269/deployimages-bd_24zmy/kernel/Image"}
- {"dt": "2023-03-15T17:43:18.300325", "lvl": "debug", "msg": "total size: 21885440 (20MB)"}
- {"dt": "2023-03-15T17:43:18.300561", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-15T17:43:18.605572", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T17:43:19.095477", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-15T17:43:19.299717", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-15T17:43:19.783720", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-15T17:43:20.076735", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-15T17:43:20.466873", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-15T17:43:20.850163", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-15T17:43:21.164923", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-15T17:43:21.539091", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-15T17:43:21.845786", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-15T17:43:22.204289", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-15T17:43:22.512908", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-15T17:43:22.812310", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-15T17:43:23.114369", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-15T17:43:23.443489", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-15T17:43:23.758942", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-15T17:43:24.067040", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-15T17:43:24.362592", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-15T17:43:24.658020", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-15T17:43:24.954295", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-15T17:43:25.247565", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-15T17:43:25.248290", "lvl": "info", "msg": "20MB downloaded in 6.95s (3.00MB/s)"}
- {"dt": "2023-03-15T17:43:25.248780", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:07) [common]"}
- {"dt": "2023-03-15T17:43:25.249029", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "6.95", "extra": {"label": "kernel", "md5sum": "48303aec3e8019f21bc725444b4ed6e6", "sha256sum": "b50dc761bd62f83daea4b5718c1ba0bbf9db024fb619c452187ded96b36658c4", "sha512sum": "b939292618c842586b14d01a7c570197e065e6fd4e85c04909e57e35ed3e7f013a4755a32705c8f77401a25790937496411c11eaf5711d1d1c3235c119087ba9", "size": ! "21885440"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:43:25.249629", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:07) [common]"}
- {"dt": "2023-03-15T17:43:25.249917", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:42) [common]"}
- {"dt": "2023-03-15T17:43:25.250205", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:42) [common]"}
- {"dt": "2023-03-15T17:43:25.250613", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28569/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-15T17:43:25.250844", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32269/deployimages-bd_24zmy/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-15T17:43:25.251059", "lvl": "debug", "msg": "total size: 475402636 (453MB)"}
- {"dt": "2023-03-15T17:43:25.251279", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-15T17:43:25.555723", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T17:43:30.877792", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-15T17:43:34.141373", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-15T17:43:37.554523", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-15T17:43:40.423415", "lvl": "debug", "msg": "progress  20% (90MB)"}
- {"dt": "2023-03-15T17:43:43.782055", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-15T17:43:47.305720", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-15T17:43:51.579268", "lvl": "debug", "msg": "progress  35% (158MB)"}
- {"dt": "2023-03-15T17:43:55.499009", "lvl": "debug", "msg": "progress  40% (181MB)"}
- {"dt": "2023-03-15T17:43:59.601703", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-15T17:44:04.404359", "lvl": "debug", "msg": "progress  50% (226MB)"}
- {"dt": "2023-03-15T17:44:09.012074", "lvl": "debug", "msg": "progress  55% (249MB)"}
- {"dt": "2023-03-15T17:44:14.100839", "lvl": "debug", "msg": "progress  60% (272MB)"}
- {"dt": "2023-03-15T17:44:19.632300", "lvl": "debug", "msg": "progress  65% (294MB)"}
- {"dt": "2023-03-15T17:44:25.082332", "lvl": "debug", "msg": "progress  70% (317MB)"}
- {"dt": "2023-03-15T17:44:30.104258", "lvl": "debug", "msg": "progress  75% (340MB)"}
- {"dt": "2023-03-15T17:44:35.399331", "lvl": "debug", "msg": "progress  80% (362MB)"}
- {"dt": "2023-03-15T17:44:40.222318", "lvl": "debug", "msg": "progress  85% (385MB)"}
- {"dt": "2023-03-15T17:44:44.578820", "lvl": "debug", "msg": "progress  90% (408MB)"}
- {"dt": "2023-03-15T17:44:47.917146", "lvl": "debug", "msg": "progress  95% (430MB)"}
- {"dt": "2023-03-15T17:44:53.514172", "lvl": "debug", "msg": "progress 100% (453MB)"}
- {"dt": "2023-03-15T17:44:55.718908", "lvl": "info", "msg": "453MB downloaded in 90.47s (5.01MB/s)"}
- {"dt": "2023-03-15T17:44:55.720889", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:30) [common]"}
- {"dt": "2023-03-15T17:44:55.721259", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "90.47", "extra": {"label": "rootvd", "md5sum": "d584df5db6d9c9137a8f335f244164ff", "sha256sum": "f31ec3a085e772a3b1f2b4d476d6b7e65cfc4078ccfc8506e4dd370e8fd4eb11", "sha512sum": "5e7d30c1dc06698af96b5de8d2c6e1d934b7c7b4d459466f0c166b956e16c6e68d3f0e584065fc5597371c8f8bd03b39354d3ebecc95d8626a129fb8aa786d16", "size": ! "475402636"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T17:44:55.722077", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:30) [common]"}
- {"dt": "2023-03-15T17:44:55.722486", "lvl": "info", "msg": "end: 1 deployimages (duration 00:01:49) [common]"}
- {"dt": "2023-03-15T17:44:55.722912", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T17:44:55.723348", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T17:44:55.723685", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T17:44:55.724766", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-15T17:44:55.725489", "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-847407 -kernel /var/lib/lava/dispatcher/tmp/32269/deployimages-bd_24zmy/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/32269/deployimages-bd_24zmy/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32269/apply-overlay-guest-7qjnd_44/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-15T17:44:56.612514", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-15T17:44:56.613515", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:44:56.613818", "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-15T17:44:56.614432", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:44:56.614767", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-15T17:44:56.615209", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-15T17:44:56.615478", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-15T17:44:56.636189", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-15T17:44:56.656982", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T17:44:56.657328", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:44:56.657603", "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-15T17:44:56.658094", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-15T17:44:56.658415", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T17:44:56.658678", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T17:44:56.658930", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.94", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-15T17:44:56.659549", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-15T17:44:56.660129", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32269/deployimages-bd_24zmy/kernel"}
- {"dt": "2023-03-15T17:44:56.665692", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32269/deployimages-bd_24zmy/rootvd"}
- {"dt": "2023-03-15T17:44:57.456146", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-15T17:44:57.456642", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:44:57.456968", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-15T17:44:57.457683", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-15T17:44:57.458089", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-15T17:44:57.559156", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T17:44:57.559521", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32269"}
- {"dt": "2023-03-15T17:44:57.581068", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-15T17:44:57.581335", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
