- {"dt": "2023-03-02T18:14:29.897357", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-02T18:14:29.897854", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-02T18:14:29.898186", "lvl": "info", "msg": "Start time: 2023-03-02 18:14:29.898162+00:00 (UTC)"}
- {"dt": "2023-03-02T18:14:29.901639", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/bzImage exists"}
- {"dt": "2023-03-02T18:14:30.104557", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz exists"}
- {"dt": "2023-03-02T18:14:30.329845", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-02T18:14:30.331330", "lvl": "info", "msg": "validate duration: 0.43"}
- {"dt": "2023-03-02T18:14:30.331611", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-02T18:14:30.332086", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.332389", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.332921", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1"}
- {"dt": "2023-03-02T18:14:30.333326", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin"}
- {"dt": "2023-03-02T18:14:30.333666", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/tests"}
- {"dt": "2023-03-02T18:14:30.333980", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/results"}
- {"dt": "2023-03-02T18:14:30.334323", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-add-keys"}
- {"dt": "2023-03-02T18:14:30.334768", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-add-sources"}
- {"dt": "2023-03-02T18:14:30.335173", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-background-process-start"}
- {"dt": "2023-03-02T18:14:30.335592", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-background-process-stop"}
- {"dt": "2023-03-02T18:14:30.335998", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-common-functions"}
- {"dt": "2023-03-02T18:14:30.336396", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-echo-ipv4"}
- {"dt": "2023-03-02T18:14:30.336823", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-install-packages"}
- {"dt": "2023-03-02T18:14:30.337229", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-installed-packages"}
- {"dt": "2023-03-02T18:14:30.337631", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-os-build"}
- {"dt": "2023-03-02T18:14:30.338026", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-probe-channel"}
- {"dt": "2023-03-02T18:14:30.338422", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-probe-ip"}
- {"dt": "2023-03-02T18:14:30.338828", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-target-ip"}
- {"dt": "2023-03-02T18:14:30.339249", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-target-mac"}
- {"dt": "2023-03-02T18:14:30.339659", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-target-storage"}
- {"dt": "2023-03-02T18:14:30.340069", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-case"}
- {"dt": "2023-03-02T18:14:30.340481", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-event"}
- {"dt": "2023-03-02T18:14:30.340882", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-feedback"}
- {"dt": "2023-03-02T18:14:30.341279", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-raise"}
- {"dt": "2023-03-02T18:14:30.341690", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-reference"}
- {"dt": "2023-03-02T18:14:30.342098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-runner"}
- {"dt": "2023-03-02T18:14:30.342498", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-set"}
- {"dt": "2023-03-02T18:14:30.342894", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-test-shell"}
- {"dt": "2023-03-02T18:14:30.343300", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-02T18:14:30.343776", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-02T18:14:30.344166", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/environment"}
- {"dt": "2023-03-02T18:14:30.344495", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-02T18:14:30.344744", "lvl": "debug", "msg": "- LAVA_JOB_ID=32124"}
- {"dt": "2023-03-02T18:14:30.344970", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-02T18:14:30.345290", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.345523", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-02T18:14:30.345790", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:30.346073", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.346295", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-02T18:14:30.346546", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:30.346811", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.347189", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-02T18:14:30.347502", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T18:14:30.347761", "lvl": "debug", "msg": "Using /lava-32124 at stage 0"}
- {"dt": "2023-03-02T18:14:30.348089", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T18:14:30.348361", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/0_ci-busybox'"}
- {"dt": "2023-03-02T18:14:31.860607", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/0_ci-busybox"}
- {"dt": "2023-03-02T18:14:31.863657", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-02T18:14:31.864919", "lvl": "debug", "msg": "uuid=32124_1.1.3.1 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-02T18:14:31.865342", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T18:14:31.865615", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.52", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:31.866172", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T18:14:31.868259", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:31.868520", "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": "32124_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:31.869109", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T18:14:31.871856", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:31.872116", "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": "32124_1.1.3.1", "uuid": "32124_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:31.872746", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T18:14:31.875358", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/0_ci-busybox test_uuid 32124_1.1.3.1"}
- {"dt": "2023-03-02T18:14:31.875854", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:31.876104", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/0_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": "32124_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:31.876733", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T18:14:31.876987", "lvl": "debug", "msg": "Using /lava-32124 at stage 0"}
- {"dt": "2023-03-02T18:14:31.877340", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T18:14:31.877608", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-03-02T18:14:33.876864", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T18:14:33.879869", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-02T18:14:33.880955", "lvl": "debug", "msg": "uuid=32124_1.1.3.5 testdef=None"}
- {"dt": "2023-03-02T18:14:33.881351", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T18:14:33.881616", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.00", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:33.882194", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-02T18:14:33.883984", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:33.884231", "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": "32124_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:33.884836", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-02T18:14:33.910349", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:33.910616", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.03", "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": "32124_1.1.3.5", "uuid": "32124_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:33.911243", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-02T18:14:33.933801", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/1_ci-smoke-tests-basic test_uuid 32124_1.1.3.5"}
- {"dt": "2023-03-02T18:14:33.934769", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:33.935027", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/1_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": "32124_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:33.935641", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-03-02T18:14:33.935897", "lvl": "debug", "msg": "Using /lava-32124 at stage 0"}
- {"dt": "2023-03-02T18:14:33.936250", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T18:14:33.936528", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/2_ci-service-check'"}
- {"dt": "2023-03-02T18:14:35.656884", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/2_ci-service-check"}
- {"dt": "2023-03-02T18:14:35.659972", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-02T18:14:35.661082", "lvl": "debug", "msg": "uuid=32124_1.1.3.9 testdef=None"}
- {"dt": "2023-03-02T18:14:35.661474", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T18:14:35.661734", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.73", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:35.662284", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T18:14:35.664149", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:35.664393", "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": "32124_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:35.664990", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T18:14:35.667447", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:35.667694", "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": "32124_1.1.3.9", "uuid": "32124_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:35.668308", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T18:14:35.688708", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/2_ci-service-check test_uuid 32124_1.1.3.9"}
- {"dt": "2023-03-02T18:14:35.688981", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-02T18:14:35.689199", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-02T18:14:35.689645", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:35.689887", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0/tests/2_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": "32124_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:35.690490", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T18:14:35.690740", "lvl": "debug", "msg": "Using /lava-32124 at stage 1"}
- {"dt": "2023-03-02T18:14:35.691081", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T18:14:35.691343", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/1/tests/3_screenshooter'"}
- {"dt": "2023-03-02T18:14:37.299854", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/1/tests/3_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-02T18:14:37.301274", "lvl": "debug", "msg": "uuid=32124_1.1.3.13 testdef=None"}
- {"dt": "2023-03-02T18:14:37.301683", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T18:14:37.301955", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.61", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:37.302509", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T18:14:37.304084", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:37.304336", "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": "32124_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:37.304934", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T18:14:37.306912", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:37.307167", "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": "32124_1.1.3.13", "uuid": "32124_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:37.307781", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T18:14:37.321797", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/1/tests/3_screenshooter test_uuid 32124_1.1.3.13"}
- {"dt": "2023-03-02T18:14:37.322299", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:37.322545", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/1/tests/3_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32124_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:37.323151", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T18:14:37.323412", "lvl": "debug", "msg": "Using /lava-32124 at stage 2"}
- {"dt": "2023-03-02T18:14:37.323766", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T18:14:37.324037", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-02T18:14:38.843183", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-02T18:14:38.846262", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-02T18:14:38.847409", "lvl": "debug", "msg": "uuid=32124_1.1.3.17 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-02T18:14:38.847816", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T18:14:38.848082", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.52", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:38.848649", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:38.850616", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:38.850862", "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": "32124_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:38.851445", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:38.854034", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:38.854287", "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": "32124_1.1.3.17", "uuid": "32124_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:38.854894", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:38.857435", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2/tests/4_ci-dumpjournal test_uuid 32124_1.1.3.17"}
- {"dt": "2023-03-02T18:14:38.857935", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:38.858182", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2/tests/4_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": "32124_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:38.858738", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-02T18:14:38.858961", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/0 for stage 0"}
- {"dt": "2023-03-02T18:14:38.859269", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-03-02T18:14:38.859505", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T18:14:38.859722", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-03-02T18:14:38.859968", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/1 for stage 1"}
- {"dt": "2023-03-02T18:14:38.860267", "lvl": "debug", "msg": "- 3_screenshooter"}
- {"dt": "2023-03-02T18:14:38.860536", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32124/lava-overlay-fijy60w1/lava-32124/2 for stage 2"}
- {"dt": "2023-03-02T18:14:38.860835", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-02T18:14:38.861150", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:09) [common]"}
- {"dt": "2023-03-02T18:14:38.861437", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:39.101048", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:39.101392", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:39.101690", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:39.102009", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2023-03-02T18:14:39.102298", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:51) [common]"}
- {"dt": "2023-03-02T18:14:39.102544", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32124/compress-overlay-awyclvu5/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-02T18:14:43.042534", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-02T18:14:43.043292", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "3.94", "extra": {"success": "2c8f5c2c-4176-4569-a94b-f18fd08f1323"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:43.043847", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:47) [common]"}
- {"dt": "2023-03-02T18:14:43.044138", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:14:43.044432", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:47) [common]"}
- {"dt": "2023-03-02T18:14:43.044752", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:47) [common]"}
- {"dt": "2023-03-02T18:14:43.045225", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/bzImage"}
- {"dt": "2023-03-02T18:14:43.045455", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/kernel/bzImage"}
- {"dt": "2023-03-02T18:14:43.045668", "lvl": "debug", "msg": "total size: 10473984 (9MB)"}
- {"dt": "2023-03-02T18:14:43.045877", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T18:14:43.342418", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T18:14:43.731143", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-02T18:14:43.831244", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-03-02T18:14:44.022645", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-03-02T18:14:44.122994", "lvl": "debug", "msg": "progress  20% (2MB)"}
- {"dt": "2023-03-02T18:14:44.223165", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-03-02T18:14:44.413143", "lvl": "debug", "msg": "progress  30% (3MB)"}
- {"dt": "2023-03-02T18:14:44.513446", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-03-02T18:14:44.612397", "lvl": "debug", "msg": "progress  40% (4MB)"}
- {"dt": "2023-03-02T18:14:44.712311", "lvl": "debug", "msg": "progress  45% (4MB)"}
- {"dt": "2023-03-02T18:14:44.896558", "lvl": "debug", "msg": "progress  50% (5MB)"}
- {"dt": "2023-03-02T18:14:44.994966", "lvl": "debug", "msg": "progress  55% (5MB)"}
- {"dt": "2023-03-02T18:14:45.093730", "lvl": "debug", "msg": "progress  60% (6MB)"}
- {"dt": "2023-03-02T18:14:45.192148", "lvl": "debug", "msg": "progress  65% (6MB)"}
- {"dt": "2023-03-02T18:14:45.289770", "lvl": "debug", "msg": "progress  70% (7MB)"}
- {"dt": "2023-03-02T18:14:45.387661", "lvl": "debug", "msg": "progress  75% (7MB)"}
- {"dt": "2023-03-02T18:14:45.484411", "lvl": "debug", "msg": "progress  80% (8MB)"}
- {"dt": "2023-03-02T18:14:45.580546", "lvl": "debug", "msg": "progress  85% (8MB)"}
- {"dt": "2023-03-02T18:14:45.677143", "lvl": "debug", "msg": "progress  90% (9MB)"}
- {"dt": "2023-03-02T18:14:45.774066", "lvl": "debug", "msg": "progress  95% (9MB)"}
- {"dt": "2023-03-02T18:14:45.784415", "lvl": "debug", "msg": "progress 100% (9MB)"}
- {"dt": "2023-03-02T18:14:45.785002", "lvl": "info", "msg": "9MB downloaded in 2.74s (3.65MB/s)"}
- {"dt": "2023-03-02T18:14:45.785454", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T18:14:45.785716", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.74", "extra": {"label": "kernel", "md5sum": "a532672a66ac7990b31084a9feb83514", "sha256sum": "15aeabdacbbf1438141c60a885c57d9a058f509ccbb412dac91a58f6605fd417", "sha512sum": "4f8ab90f405878db2776819a72abc859e77a088e1452b8c06f82ea48f5115e9a91c66fe976ac9abc3c54d066555501f5f1791a93b5c4c39d2ab91d278e7ed2cd", "size": ! "10473984"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:14:45.786321", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T18:14:45.786605", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:45) [common]"}
- {"dt": "2023-03-02T18:14:45.786894", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:45) [common]"}
- {"dt": "2023-03-02T18:14:45.787265", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz"}
- {"dt": "2023-03-02T18:14:45.787494", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-03-02T18:14:45.787707", "lvl": "debug", "msg": "total size: 541892616 (516MB)"}
- {"dt": "2023-03-02T18:14:45.787925", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-02T18:14:46.185682", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T18:14:54.141240", "lvl": "debug", "msg": "progress   5% (25MB)"}
- {"dt": "2023-03-02T18:15:02.495583", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-03-02T18:15:22.011844", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-03-02T18:15:33.987728", "lvl": "debug", "msg": "progress  20% (103MB)"}
- {"dt": "2023-03-02T18:15:39.852692", "lvl": "debug", "msg": "progress  25% (129MB)"}
- {"dt": "2023-03-02T18:15:45.606023", "lvl": "debug", "msg": "progress  30% (155MB)"}
- {"dt": "2023-03-02T18:16:01.026560", "lvl": "debug", "msg": "progress  35% (180MB)"}
- {"dt": "2023-03-02T18:16:06.113664", "lvl": "debug", "msg": "progress  40% (206MB)"}
- {"dt": "2023-03-02T18:16:11.671776", "lvl": "debug", "msg": "progress  45% (232MB)"}
- {"dt": "2023-03-02T18:16:17.867079", "lvl": "debug", "msg": "progress  50% (258MB)"}
- {"dt": "2023-03-02T18:16:30.429510", "lvl": "debug", "msg": "progress  55% (284MB)"}
- {"dt": "2023-03-02T18:16:36.687807", "lvl": "debug", "msg": "progress  60% (310MB)"}
- {"dt": "2023-03-02T18:16:44.393615", "lvl": "debug", "msg": "progress  65% (335MB)"}
- {"dt": "2023-03-02T18:16:52.479049", "lvl": "debug", "msg": "progress  70% (361MB)"}
- {"dt": "2023-03-02T18:16:57.950601", "lvl": "debug", "msg": "progress  75% (387MB)"}
- {"dt": "2023-03-02T18:17:06.783971", "lvl": "debug", "msg": "progress  80% (413MB)"}
- {"dt": "2023-03-02T18:17:13.545813", "lvl": "debug", "msg": "progress  85% (439MB)"}
- {"dt": "2023-03-02T18:17:17.178354", "lvl": "debug", "msg": "progress  90% (465MB)"}
- {"dt": "2023-03-02T18:17:21.063605", "lvl": "debug", "msg": "progress  95% (490MB)"}
- {"dt": "2023-03-02T18:17:27.743678", "lvl": "debug", "msg": "progress 100% (516MB)"}
- {"dt": "2023-03-02T18:17:29.923362", "lvl": "info", "msg": "516MB downloaded in 164.14s (3.15MB/s)"}
- {"dt": "2023-03-02T18:17:29.924168", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:02:44) [common]"}
- {"dt": "2023-03-02T18:17:29.924446", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "164.14", "extra": {"label": "rootvd", "md5sum": "2e0d26039abb51c5176e22e54cced201", "sha256sum": "4bc7625bc60d8ef981441022160c3bb4d4fcfdc9878d603b4c1f386fcc42db8e", "sha512sum": "a6ad2304b7927c7ec3a9f2e8febf1f105a478686711f84627b0812c1182d00734d3faeb053922e47cc444025f10cd36cd5b97d3c2439791611a993d6a55272d0", "size": ! "541892616"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:17:29.925113", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:02:44) [common]"}
- {"dt": "2023-03-02T18:17:29.925406", "lvl": "info", "msg": "end: 1 deployimages (duration 00:03:00) [common]"}
- {"dt": "2023-03-02T18:17:29.925722", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T18:17:29.926023", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T18:17:29.926317", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T18:17:29.926941", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-02T18:17:29.927413", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-x86_64 -cpu host -enable-kvm -nographic -net nic,model=virtio,macaddr=52:54:00:12:34:58 -net user -m 512 -monitor none -machine q35 -cpu qemu64,+ssse3,+sse4.1,+sse4.2,+popcnt -smp 2 -m 2048 -soundhw hda -device usb-ehci -device virtio-rng-pci -vga virtio -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc unix:/tmp/vnc-AGL-qemux86-64-919886 -kernel /var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/kernel/bzImage -append \"console=ttyS0,115200 root=/dev/sda verbose systemd.log_color=false rw fstab=no no_timer_check\" -drive format=raw,file=/var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32124/apply-overlay-guest-qlxmsbo6/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-03-02T18:17:30.811626", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-02T18:17:30.812680", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T18:17:30.812976", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.89", "extra": {"host_arch": "amd64", "job_arch": "x86_64", "qemu_version": "1:7.2+dfsg-1~bpo11+2"}, "level": "2.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T18:17:30.813585", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T18:17:30.813892", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-02T18:17:30.814193", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-02T18:17:30.814448", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-02T18:17:30.834260", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-03-02T18:17:30.856915", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-02T18:17:30.857202", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:17:30.857467", "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-02T18:17:30.857923", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-02T18:17:30.858202", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-02T18:17:30.858448", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T18:17:30.858698", "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-02T18:17:30.859208", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-02T18:17:30.859560", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/kernel"}
- {"dt": "2023-03-02T18:17:30.862154", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32124/deployimages-pwmikbqm/rootvd"}
- {"dt": "2023-03-02T18:17:31.429927", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-02T18:17:31.430358", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:17:31.430663", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-02T18:17:31.431278", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-02T18:17:31.431668", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-02T18:17:31.532676", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T18:17:31.533081", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32124"}
- {"dt": "2023-03-02T18:17:31.553402", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-02T18:17:31.553667", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
