- {"dt": "2023-03-02T17:06:30.238525", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-02T17:06:30.238995", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-02T17:06:30.239326", "lvl": "info", "msg": "Start time: 2023-03-02 17:06:30.239303+00:00 (UTC)"}
- {"dt": "2023-03-02T17:06:30.242776", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/bzImage exists"}
- {"dt": "2023-03-02T17:06:30.451140", "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-02T17:06:30.675686", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-02T17:06:30.677258", "lvl": "info", "msg": "validate duration: 0.44"}
- {"dt": "2023-03-02T17:06:30.677552", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-02T17:06:30.678077", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.678401", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.678959", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt"}
- {"dt": "2023-03-02T17:06:30.679394", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin"}
- {"dt": "2023-03-02T17:06:30.679751", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/tests"}
- {"dt": "2023-03-02T17:06:30.680095", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/results"}
- {"dt": "2023-03-02T17:06:30.680481", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-add-keys"}
- {"dt": "2023-03-02T17:06:30.680981", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-add-sources"}
- {"dt": "2023-03-02T17:06:30.681443", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-background-process-start"}
- {"dt": "2023-03-02T17:06:30.681904", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-background-process-stop"}
- {"dt": "2023-03-02T17:06:30.682349", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-common-functions"}
- {"dt": "2023-03-02T17:06:30.682797", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-echo-ipv4"}
- {"dt": "2023-03-02T17:06:30.683232", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-install-packages"}
- {"dt": "2023-03-02T17:06:30.683666", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-installed-packages"}
- {"dt": "2023-03-02T17:06:30.684112", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-os-build"}
- {"dt": "2023-03-02T17:06:30.684597", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-probe-channel"}
- {"dt": "2023-03-02T17:06:30.685057", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-probe-ip"}
- {"dt": "2023-03-02T17:06:30.685498", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-target-ip"}
- {"dt": "2023-03-02T17:06:30.685944", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-target-mac"}
- {"dt": "2023-03-02T17:06:30.686380", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-target-storage"}
- {"dt": "2023-03-02T17:06:30.686824", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-case"}
- {"dt": "2023-03-02T17:06:30.687257", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-event"}
- {"dt": "2023-03-02T17:06:30.687690", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-feedback"}
- {"dt": "2023-03-02T17:06:30.688132", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-raise"}
- {"dt": "2023-03-02T17:06:30.688609", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-reference"}
- {"dt": "2023-03-02T17:06:30.689046", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-runner"}
- {"dt": "2023-03-02T17:06:30.689475", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-set"}
- {"dt": "2023-03-02T17:06:30.689905", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-test-shell"}
- {"dt": "2023-03-02T17:06:30.690339", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-02T17:06:30.690829", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-02T17:06:30.691242", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/environment"}
- {"dt": "2023-03-02T17:06:30.691588", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-02T17:06:30.691839", "lvl": "debug", "msg": "- LAVA_JOB_ID=32113"}
- {"dt": "2023-03-02T17:06:30.692076", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-02T17:06:30.692413", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.692669", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-02T17:06:30.692947", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:30.693239", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.693467", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-02T17:06:30.693729", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:30.694001", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.694412", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-02T17:06:30.694764", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T17:06:30.695040", "lvl": "debug", "msg": "Using /lava-32113 at stage 0"}
- {"dt": "2023-03-02T17:06:30.695393", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T17:06:30.695679", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/0_ci-busybox'"}
- {"dt": "2023-03-02T17:06:33.859148", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/0_ci-busybox"}
- {"dt": "2023-03-02T17:06:33.862525", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-02T17:06:33.863842", "lvl": "debug", "msg": "uuid=32113_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-02T17:06:33.864295", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T17:06:33.864601", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.17", "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-02T17:06:33.865196", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T17:06:33.867423", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:33.867689", "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": "32113_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:33.868303", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T17:06:33.871187", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:33.871454", "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": "32113_1.1.3.1", "uuid": "32113_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:33.872081", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T17:06:33.901017", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/0_ci-busybox test_uuid 32113_1.1.3.1"}
- {"dt": "2023-03-02T17:06:33.901566", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:33.901845", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/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": "32113_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:33.902493", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T17:06:33.902767", "lvl": "debug", "msg": "Using /lava-32113 at stage 0"}
- {"dt": "2023-03-02T17:06:33.903153", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T17:06:33.903442", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-03-02T17:06:35.534643", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T17:06:35.758319", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-02T17:06:35.759568", "lvl": "debug", "msg": "uuid=32113_1.1.3.5 testdef=None"}
- {"dt": "2023-03-02T17:06:35.760000", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T17:06:35.760293", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.86", "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-02T17:06:35.760922", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T17:06:35.762904", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:35.763176", "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": "32113_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:35.763791", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T17:06:35.766356", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:35.766632", "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": "32113_1.1.3.5", "uuid": "32113_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:35.767290", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T17:06:35.840839", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/1_ci-smoke-tests-basic test_uuid 32113_1.1.3.5"}
- {"dt": "2023-03-02T17:06:35.841918", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:35.842204", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.07", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/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": "32113_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:35.842847", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-02T17:06:35.843126", "lvl": "debug", "msg": "Using /lava-32113 at stage 0"}
- {"dt": "2023-03-02T17:06:35.843520", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T17:06:35.843817", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/2_ci-service-check'"}
- {"dt": "2023-03-02T17:06:37.412809", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/2_ci-service-check"}
- {"dt": "2023-03-02T17:06:37.416060", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-02T17:06:37.417235", "lvl": "debug", "msg": "uuid=32113_1.1.3.9 testdef=None"}
- {"dt": "2023-03-02T17:06:37.417661", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T17:06:37.417948", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.57", "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-02T17:06:37.418532", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T17:06:37.420537", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:37.420815", "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": "32113_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:37.421426", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T17:06:37.424035", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:37.424312", "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": "32113_1.1.3.9", "uuid": "32113_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:37.424965", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T17:06:37.427530", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0/tests/2_ci-service-check test_uuid 32113_1.1.3.9"}
- {"dt": "2023-03-02T17:06:37.427820", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-02T17:06:37.428054", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-02T17:06:37.428557", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:37.428813", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/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": "32113_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:37.429434", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-02T17:06:37.429705", "lvl": "debug", "msg": "Using /lava-32113 at stage 1"}
- {"dt": "2023-03-02T17:06:37.430079", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T17:06:37.430369", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/1/tests/3_screenshooter'"}
- {"dt": "2023-03-02T17:06:38.923013", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/1/tests/3_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-02T17:06:38.924571", "lvl": "debug", "msg": "uuid=32113_1.1.3.13 testdef=None"}
- {"dt": "2023-03-02T17:06:38.925014", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T17:06:38.925308", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.50", "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-02T17:06:38.925904", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-02T17:06:38.927594", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:38.927863", "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": "32113_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:38.928505", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-02T17:06:38.930666", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:38.930946", "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": "32113_1.1.3.13", "uuid": "32113_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:38.931582", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-02T17:06:38.933665", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/1/tests/3_screenshooter test_uuid 32113_1.1.3.13"}
- {"dt": "2023-03-02T17:06:38.934231", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:38.934493", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/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": "32113_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:38.935122", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-03-02T17:06:38.935403", "lvl": "debug", "msg": "Using /lava-32113 at stage 2"}
- {"dt": "2023-03-02T17:06:38.935779", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T17:06:38.936066", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-02T17:06:40.417766", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-02T17:06:40.422162", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-02T17:06:40.423884", "lvl": "debug", "msg": "uuid=32113_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-02T17:06:40.424423", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T17:06:40.424804", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.49", "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-02T17:06:40.425575", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.428314", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:40.428761", "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": "32113_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:40.429625", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.433046", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:40.433366", "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": "32113_1.1.3.17", "uuid": "32113_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:40.434148", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.437472", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/2/tests/4_ci-dumpjournal test_uuid 32113_1.1.3.17"}
- {"dt": "2023-03-02T17:06:40.438160", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:40.438511", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/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": "32113_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:40.439326", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-02T17:06:40.439651", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/0 for stage 0"}
- {"dt": "2023-03-02T17:06:40.440086", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-03-02T17:06:40.440430", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T17:06:40.440757", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-03-02T17:06:40.441074", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/1 for stage 1"}
- {"dt": "2023-03-02T17:06:40.441441", "lvl": "debug", "msg": "- 3_screenshooter"}
- {"dt": "2023-03-02T17:06:40.441740", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32113/lava-overlay-ufitpjtt/lava-32113/2 for stage 2"}
- {"dt": "2023-03-02T17:06:40.442098", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-02T17:06:40.442481", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:10) [common]"}
- {"dt": "2023-03-02T17:06:40.442816", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.695149", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:40.695496", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.695777", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:40.696077", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:10) [common]"}
- {"dt": "2023-03-02T17:06:40.696361", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:50) [common]"}
- {"dt": "2023-03-02T17:06:40.696623", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32113/compress-overlay-_kcfxd3l/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-02T17:06:44.880086", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-02T17:06:44.880859", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "4.18", "extra": {"success": "8a624915-a31f-4551-8ee7-1d128fd7d1a7"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:44.881418", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:46) [common]"}
- {"dt": "2023-03-02T17:06:44.881718", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:06:44.882020", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:46) [common]"}
- {"dt": "2023-03-02T17:06:44.882335", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:46) [common]"}
- {"dt": "2023-03-02T17:06:44.882834", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28482/1/qemux86-64/bzImage"}
- {"dt": "2023-03-02T17:06:44.883070", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32113/deployimages-0b_djghy/kernel/bzImage"}
- {"dt": "2023-03-02T17:06:44.883293", "lvl": "debug", "msg": "total size: 10473984 (9MB)"}
- {"dt": "2023-03-02T17:06:44.883505", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T17:06:45.271525", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T17:06:45.649881", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-02T17:06:45.745621", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-03-02T17:06:45.840044", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-03-02T17:06:45.934485", "lvl": "debug", "msg": "progress  20% (2MB)"}
- {"dt": "2023-03-02T17:06:46.026835", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-03-02T17:06:46.037888", "lvl": "debug", "msg": "progress  30% (3MB)"}
- {"dt": "2023-03-02T17:06:46.132536", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-03-02T17:06:46.226539", "lvl": "debug", "msg": "progress  40% (4MB)"}
- {"dt": "2023-03-02T17:06:46.320779", "lvl": "debug", "msg": "progress  45% (4MB)"}
- {"dt": "2023-03-02T17:06:46.415402", "lvl": "debug", "msg": "progress  50% (5MB)"}
- {"dt": "2023-03-02T17:06:46.510513", "lvl": "debug", "msg": "progress  55% (5MB)"}
- {"dt": "2023-03-02T17:06:46.604602", "lvl": "debug", "msg": "progress  60% (6MB)"}
- {"dt": "2023-03-02T17:06:46.699450", "lvl": "debug", "msg": "progress  65% (6MB)"}
- {"dt": "2023-03-02T17:06:46.793487", "lvl": "debug", "msg": "progress  70% (7MB)"}
- {"dt": "2023-03-02T17:06:46.888722", "lvl": "debug", "msg": "progress  75% (7MB)"}
- {"dt": "2023-03-02T17:06:46.981559", "lvl": "debug", "msg": "progress  80% (8MB)"}
- {"dt": "2023-03-02T17:06:47.028400", "lvl": "debug", "msg": "progress  85% (8MB)"}
- {"dt": "2023-03-02T17:06:47.087511", "lvl": "debug", "msg": "progress  90% (9MB)"}
- {"dt": "2023-03-02T17:06:47.181534", "lvl": "debug", "msg": "progress  95% (9MB)"}
- {"dt": "2023-03-02T17:06:47.274747", "lvl": "debug", "msg": "progress 100% (9MB)"}
- {"dt": "2023-03-02T17:06:47.275339", "lvl": "info", "msg": "9MB downloaded in 2.39s (4.18MB/s)"}
- {"dt": "2023-03-02T17:06:47.275784", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T17:06:47.276046", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.39", "extra": {"label": "kernel", "md5sum": "a532672a66ac7990b31084a9feb83514", "sha256sum": "15aeabdacbbf1438141c60a885c57d9a058f509ccbb412dac91a58f6605fd417", "sha512sum": "4f8ab90f405878db2776819a72abc859e77a088e1452b8c06f82ea48f5115e9a91c66fe976ac9abc3c54d066555501f5f1791a93b5c4c39d2ab91d278e7ed2cd", "size": ! "10473984"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:06:47.276684", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T17:06:47.276976", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:43) [common]"}
- {"dt": "2023-03-02T17:06:47.277269", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:43) [common]"}
- {"dt": "2023-03-02T17:06:47.277645", "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-02T17:06:47.277881", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32113/deployimages-0b_djghy/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-03-02T17:06:47.278100", "lvl": "debug", "msg": "total size: 541892616 (516MB)"}
- {"dt": "2023-03-02T17:06:47.278320", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-02T17:06:47.575410", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T17:06:52.050401", "lvl": "debug", "msg": "progress   5% (25MB)"}
- {"dt": "2023-03-02T17:06:55.941317", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-03-02T17:06:59.763366", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-03-02T17:07:03.639488", "lvl": "debug", "msg": "progress  20% (103MB)"}
- {"dt": "2023-03-02T17:07:07.395715", "lvl": "debug", "msg": "progress  25% (129MB)"}
- {"dt": "2023-03-02T17:07:11.433798", "lvl": "debug", "msg": "progress  30% (155MB)"}
- {"dt": "2023-03-02T17:07:15.614449", "lvl": "debug", "msg": "progress  35% (180MB)"}
- {"dt": "2023-03-02T17:07:20.136587", "lvl": "debug", "msg": "progress  40% (206MB)"}
- {"dt": "2023-03-02T17:07:25.212373", "lvl": "debug", "msg": "progress  45% (232MB)"}
- {"dt": "2023-03-02T17:07:30.533109", "lvl": "debug", "msg": "progress  50% (258MB)"}
- {"dt": "2023-03-02T17:07:34.615809", "lvl": "debug", "msg": "progress  55% (284MB)"}
- {"dt": "2023-03-02T17:07:39.332875", "lvl": "debug", "msg": "progress  60% (310MB)"}
- {"dt": "2023-03-02T17:07:50.492674", "lvl": "debug", "msg": "progress  65% (335MB)"}
- {"dt": "2023-03-02T17:07:56.813737", "lvl": "debug", "msg": "progress  70% (361MB)"}
- {"dt": "2023-03-02T17:08:00.882976", "lvl": "debug", "msg": "progress  75% (387MB)"}
- {"dt": "2023-03-02T17:08:04.438415", "lvl": "debug", "msg": "progress  80% (413MB)"}
- {"dt": "2023-03-02T17:08:07.962607", "lvl": "debug", "msg": "progress  85% (439MB)"}
- {"dt": "2023-03-02T17:08:11.587583", "lvl": "debug", "msg": "progress  90% (465MB)"}
- {"dt": "2023-03-02T17:08:15.094808", "lvl": "debug", "msg": "progress  95% (490MB)"}
- {"dt": "2023-03-02T17:08:21.053702", "lvl": "debug", "msg": "progress 100% (516MB)"}
- {"dt": "2023-03-02T17:08:23.235910", "lvl": "info", "msg": "516MB downloaded in 95.96s (5.39MB/s)"}
- {"dt": "2023-03-02T17:08:23.237956", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:36) [common]"}
- {"dt": "2023-03-02T17:08:23.238313", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "95.96", "extra": {"label": "rootvd", "md5sum": "2e0d26039abb51c5176e22e54cced201", "sha256sum": "4bc7625bc60d8ef981441022160c3bb4d4fcfdc9878d603b4c1f386fcc42db8e", "sha512sum": "a6ad2304b7927c7ec3a9f2e8febf1f105a478686711f84627b0812c1182d00734d3faeb053922e47cc444025f10cd36cd5b97d3c2439791611a993d6a55272d0", "size": ! "541892616"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T17:08:23.239176", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:36) [common]"}
- {"dt": "2023-03-02T17:08:23.239554", "lvl": "info", "msg": "end: 1 deployimages (duration 00:01:53) [common]"}
- {"dt": "2023-03-02T17:08:23.239983", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T17:08:23.240364", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T17:08:23.240709", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T17:08:23.241750", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-02T17:08:23.242444", "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-780253 -kernel /var/lib/lava/dispatcher/tmp/32113/deployimages-0b_djghy/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/32113/deployimages-0b_djghy/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32113/apply-overlay-guest-r0ppoo1y/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-03-02T17:08:24.130625", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-02T17:08:24.131765", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T17:08:24.132065", "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-02T17:08:24.132688", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T17:08:24.133013", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-02T17:08:24.133419", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-02T17:08:24.133693", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-02T17:08:24.152555", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-03-02T17:08:24.180944", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-02T17:08:24.181251", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:08:24.181528", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.05", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T17:08:24.182009", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-02T17:08:24.182353", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-02T17:08:24.182623", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T17:08:24.182908", "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-02T17:08:24.183501", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-02T17:08:24.184068", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32113/deployimages-0b_djghy/kernel"}
- {"dt": "2023-03-02T17:08:24.187060", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32113/deployimages-0b_djghy/rootvd"}
- {"dt": "2023-03-02T17:08:24.772975", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-02T17:08:24.773481", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:08:24.773795", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-02T17:08:24.774472", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-02T17:08:24.774893", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-02T17:08:24.875938", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T17:08:24.876399", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32113"}
- {"dt": "2023-03-02T17:08:24.910184", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-02T17:08:24.910556", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
