- {"dt": "2023-03-06T23:50:55.293345", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-06T23:50:55.293823", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-06T23:50:55.294168", "lvl": "info", "msg": "Start time: 2023-03-06 23:50:55.294137+00:00 (UTC)"}
- {"dt": "2023-03-06T23:50:55.297613", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28562/1/qemux86-64/bzImage exists"}
- {"dt": "2023-03-06T23:50:55.497500", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28562/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz exists"}
- {"dt": "2023-03-06T23:50:55.720998", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-06T23:50:55.722479", "lvl": "info", "msg": "validate duration: 0.43"}
- {"dt": "2023-03-06T23:50:55.722753", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-06T23:50:55.723234", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.723545", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.724066", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w"}
- {"dt": "2023-03-06T23:50:55.724486", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin"}
- {"dt": "2023-03-06T23:50:55.724841", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/tests"}
- {"dt": "2023-03-06T23:50:55.725169", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/results"}
- {"dt": "2023-03-06T23:50:55.725523", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-add-keys"}
- {"dt": "2023-03-06T23:50:55.725977", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-add-sources"}
- {"dt": "2023-03-06T23:50:55.726395", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-background-process-start"}
- {"dt": "2023-03-06T23:50:55.726824", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-background-process-stop"}
- {"dt": "2023-03-06T23:50:55.727237", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-common-functions"}
- {"dt": "2023-03-06T23:50:55.727658", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-echo-ipv4"}
- {"dt": "2023-03-06T23:50:55.728070", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-install-packages"}
- {"dt": "2023-03-06T23:50:55.728491", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-installed-packages"}
- {"dt": "2023-03-06T23:50:55.728930", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-os-build"}
- {"dt": "2023-03-06T23:50:55.729392", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-probe-channel"}
- {"dt": "2023-03-06T23:50:55.729828", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-probe-ip"}
- {"dt": "2023-03-06T23:50:55.730244", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-target-ip"}
- {"dt": "2023-03-06T23:50:55.730660", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-target-mac"}
- {"dt": "2023-03-06T23:50:55.731069", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-target-storage"}
- {"dt": "2023-03-06T23:50:55.731485", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-case"}
- {"dt": "2023-03-06T23:50:55.731892", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-event"}
- {"dt": "2023-03-06T23:50:55.732295", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-feedback"}
- {"dt": "2023-03-06T23:50:55.732717", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-raise"}
- {"dt": "2023-03-06T23:50:55.733134", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-reference"}
- {"dt": "2023-03-06T23:50:55.733547", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-runner"}
- {"dt": "2023-03-06T23:50:55.733958", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-set"}
- {"dt": "2023-03-06T23:50:55.734369", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-test-shell"}
- {"dt": "2023-03-06T23:50:55.734786", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-06T23:50:55.735271", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-06T23:50:55.735671", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/environment"}
- {"dt": "2023-03-06T23:50:55.735999", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-06T23:50:55.736238", "lvl": "debug", "msg": "- LAVA_JOB_ID=32152"}
- {"dt": "2023-03-06T23:50:55.736479", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-06T23:50:55.736808", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.737042", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-06T23:50:55.737311", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:50:55.737595", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.737819", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-06T23:50:55.738073", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:50:55.738344", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.738724", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-06T23:50:55.739043", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-06T23:50:55.739307", "lvl": "debug", "msg": "Using /lava-32152 at stage 0"}
- {"dt": "2023-03-06T23:50:55.739640", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-06T23:50:55.739910", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/0_ci-busybox'"}
- {"dt": "2023-03-06T23:50:59.708902", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/0_ci-busybox"}
- {"dt": "2023-03-06T23:50:59.712493", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-06T23:50:59.713849", "lvl": "debug", "msg": "uuid=32152_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-06T23:50:59.714333", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:04) [common]"}
- {"dt": "2023-03-06T23:50:59.714638", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.98", "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-06T23:50:59.715257", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-06T23:50:59.717571", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:50:59.717849", "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": "32152_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:50:59.718487", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-06T23:50:59.721428", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:50:59.721709", "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": "32152_1.1.3.1", "uuid": "32152_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:50:59.722355", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-06T23:50:59.742351", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/0_ci-busybox test_uuid 32152_1.1.3.1"}
- {"dt": "2023-03-06T23:50:59.742939", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:50:59.743235", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/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": "32152_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:50:59.743899", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-03-06T23:50:59.744190", "lvl": "debug", "msg": "Using /lava-32152 at stage 0"}
- {"dt": "2023-03-06T23:50:59.744604", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-06T23:50:59.744911", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-03-06T23:51:01.847808", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-03-06T23:51:01.851255", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-06T23:51:01.852411", "lvl": "debug", "msg": "uuid=32152_1.1.3.5 testdef=None"}
- {"dt": "2023-03-06T23:51:01.852935", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-06T23:51:01.853241", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.11", "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-06T23:51:01.853849", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-06T23:51:01.855848", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:01.856124", "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": "32152_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:01.856787", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-06T23:51:01.859357", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:01.859654", "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": "32152_1.1.3.5", "uuid": "32152_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:01.860313", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-06T23:51:01.865316", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/1_ci-smoke-tests-basic test_uuid 32152_1.1.3.5"}
- {"dt": "2023-03-06T23:51:01.866368", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:01.866653", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/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": "32152_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:01.867299", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-03-06T23:51:01.867577", "lvl": "debug", "msg": "Using /lava-32152 at stage 0"}
- {"dt": "2023-03-06T23:51:01.867963", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-06T23:51:01.868255", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/2_ci-service-check'"}
- {"dt": "2023-03-06T23:51:03.418483", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/2_ci-service-check"}
- {"dt": "2023-03-06T23:51:03.421730", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-06T23:51:03.422867", "lvl": "debug", "msg": "uuid=32152_1.1.3.9 testdef=None"}
- {"dt": "2023-03-06T23:51:03.423308", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-06T23:51:03.423603", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.56", "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-06T23:51:03.424203", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-06T23:51:03.426227", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:03.426505", "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": "32152_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:03.427128", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-06T23:51:03.429812", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:03.430102", "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": "32152_1.1.3.9", "uuid": "32152_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:03.430764", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-06T23:51:03.433362", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0/tests/2_ci-service-check test_uuid 32152_1.1.3.9"}
- {"dt": "2023-03-06T23:51:03.433659", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-06T23:51:03.433902", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-06T23:51:03.434401", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:03.434666", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/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": "32152_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:03.435303", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-03-06T23:51:03.435584", "lvl": "debug", "msg": "Using /lava-32152 at stage 1"}
- {"dt": "2023-03-06T23:51:03.435969", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-06T23:51:03.436264", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/1/tests/3_screenshooter'"}
- {"dt": "2023-03-06T23:51:04.922027", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/1/tests/3_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-06T23:51:04.923501", "lvl": "debug", "msg": "uuid=32152_1.1.3.13 testdef=None"}
- {"dt": "2023-03-06T23:51:04.923955", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-06T23:51:04.924258", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.49", "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-06T23:51:04.924895", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-06T23:51:04.926671", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:04.926950", "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": "32152_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:04.927578", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-06T23:51:04.929819", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:04.930108", "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": "32152_1.1.3.13", "uuid": "32152_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:04.930757", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-06T23:51:04.932875", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/1/tests/3_screenshooter test_uuid 32152_1.1.3.13"}
- {"dt": "2023-03-06T23:51:04.933448", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:04.933738", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/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": "32152_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:04.934385", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:51) [common]"}
- {"dt": "2023-03-06T23:51:04.934682", "lvl": "debug", "msg": "Using /lava-32152 at stage 2"}
- {"dt": "2023-03-06T23:51:04.935078", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-06T23:51:04.935375", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-06T23:51:06.426961", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-06T23:51:06.430249", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-06T23:51:06.431473", "lvl": "debug", "msg": "uuid=32152_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-06T23:51:06.431923", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-06T23:51:06.432219", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.50", "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-06T23:51:06.432845", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.434963", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:06.435277", "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": "32152_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:06.435900", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.438730", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:06.439012", "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": "32152_1.1.3.17", "uuid": "32152_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:06.439683", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.442383", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/2/tests/4_ci-dumpjournal test_uuid 32152_1.1.3.17"}
- {"dt": "2023-03-06T23:51:06.442951", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:06.443222", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/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": "32152_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:06.443805", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-06T23:51:06.444051", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/0 for stage 0"}
- {"dt": "2023-03-06T23:51:06.444388", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-03-06T23:51:06.444668", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-03-06T23:51:06.444939", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-03-06T23:51:06.445215", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/1 for stage 1"}
- {"dt": "2023-03-06T23:51:06.445547", "lvl": "debug", "msg": "- 3_screenshooter"}
- {"dt": "2023-03-06T23:51:06.445834", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32152/lava-overlay-v9thy9_w/lava-32152/2 for stage 2"}
- {"dt": "2023-03-06T23:51:06.446166", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-06T23:51:06.446521", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:11) [common]"}
- {"dt": "2023-03-06T23:51:06.446843", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.688019", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:06.688353", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.688664", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:06.688988", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:11) [common]"}
- {"dt": "2023-03-06T23:51:06.689284", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:49) [common]"}
- {"dt": "2023-03-06T23:51:06.689539", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32152/compress-overlay-pyy9gvcf/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-06T23:51:10.566501", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-06T23:51:10.567267", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "3.88", "extra": {"success": "ca3c960f-f351-443a-bd82-0d55d9afa46a"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:10.567837", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:45) [common]"}
- {"dt": "2023-03-06T23:51:10.568136", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:51:10.568439", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:45) [common]"}
- {"dt": "2023-03-06T23:51:10.568783", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:45) [common]"}
- {"dt": "2023-03-06T23:51:10.569263", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28562/1/qemux86-64/bzImage"}
- {"dt": "2023-03-06T23:51:10.569496", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32152/deployimages-d9sa8hio/kernel/bzImage"}
- {"dt": "2023-03-06T23:51:10.569717", "lvl": "debug", "msg": "total size: 10508416 (10MB)"}
- {"dt": "2023-03-06T23:51:10.569926", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-06T23:51:10.870860", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-06T23:51:12.166295", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-06T23:51:13.323538", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-03-06T23:51:14.371172", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-03-06T23:51:15.230586", "lvl": "debug", "msg": "progress  20% (2MB)"}
- {"dt": "2023-03-06T23:51:16.001496", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-03-06T23:51:16.773464", "lvl": "debug", "msg": "progress  30% (3MB)"}
- {"dt": "2023-03-06T23:51:17.631916", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-03-06T23:51:18.348481", "lvl": "debug", "msg": "progress  40% (4MB)"}
- {"dt": "2023-03-06T23:51:19.064253", "lvl": "debug", "msg": "progress  45% (4MB)"}
- {"dt": "2023-03-06T23:51:19.736570", "lvl": "debug", "msg": "progress  50% (5MB)"}
- {"dt": "2023-03-06T23:51:20.525072", "lvl": "debug", "msg": "progress  55% (5MB)"}
- {"dt": "2023-03-06T23:51:21.286973", "lvl": "debug", "msg": "progress  60% (6MB)"}
- {"dt": "2023-03-06T23:51:21.957105", "lvl": "debug", "msg": "progress  65% (6MB)"}
- {"dt": "2023-03-06T23:51:22.570742", "lvl": "debug", "msg": "progress  70% (7MB)"}
- {"dt": "2023-03-06T23:51:23.231959", "lvl": "debug", "msg": "progress  75% (7MB)"}
- {"dt": "2023-03-06T23:51:23.850282", "lvl": "debug", "msg": "progress  80% (8MB)"}
- {"dt": "2023-03-06T23:51:24.437752", "lvl": "debug", "msg": "progress  85% (8MB)"}
- {"dt": "2023-03-06T23:51:25.144105", "lvl": "debug", "msg": "progress  90% (9MB)"}
- {"dt": "2023-03-06T23:51:25.807392", "lvl": "debug", "msg": "progress  95% (9MB)"}
- {"dt": "2023-03-06T23:51:26.380315", "lvl": "debug", "msg": "progress 100% (10MB)"}
- {"dt": "2023-03-06T23:51:26.380998", "lvl": "info", "msg": "10MB downloaded in 15.81s (0.63MB/s)"}
- {"dt": "2023-03-06T23:51:26.381521", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:16) [common]"}
- {"dt": "2023-03-06T23:51:26.381814", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "15.81", "extra": {"label": "kernel", "md5sum": "d08bc5b6eca2b4f26b34d786ecaf6d57", "sha256sum": "4ec02cd487c4c00c8d7e4b87a61a54007d9091162deb31587df0ea6ca666e8a0", "sha512sum": "d71db2445641181ed88292c8f097cf7970d1181a503ca5365729814db1254240ba8cda54907aac71dd28de2fce754173450ce34759055eb99f208c1f15e3eb76", "size": ! "10508416"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:51:26.382483", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:16) [common]"}
- {"dt": "2023-03-06T23:51:26.382819", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:29) [common]"}
- {"dt": "2023-03-06T23:51:26.383148", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:29) [common]"}
- {"dt": "2023-03-06T23:51:26.383589", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28562/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz"}
- {"dt": "2023-03-06T23:51:26.383849", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32152/deployimages-d9sa8hio/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-03-06T23:51:26.384090", "lvl": "debug", "msg": "total size: 543340368 (518MB)"}
- {"dt": "2023-03-06T23:51:26.384326", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-06T23:51:26.696289", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-06T23:51:56.972941", "lvl": "debug", "msg": "progress   5% (25MB)"}
- {"dt": "2023-03-06T23:52:09.924751", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-03-06T23:52:14.434756", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-03-06T23:52:22.306565", "lvl": "debug", "msg": "progress  20% (103MB)"}
- {"dt": "2023-03-06T23:52:30.167285", "lvl": "debug", "msg": "progress  25% (129MB)"}
- {"dt": "2023-03-06T23:52:37.232286", "lvl": "debug", "msg": "progress  30% (155MB)"}
- {"dt": "2023-03-06T23:52:42.699415", "lvl": "debug", "msg": "progress  35% (181MB)"}
- {"dt": "2023-03-06T23:52:47.264418", "lvl": "debug", "msg": "progress  40% (207MB)"}
- {"dt": "2023-03-06T23:52:55.790394", "lvl": "debug", "msg": "progress  45% (233MB)"}
- {"dt": "2023-03-06T23:53:05.240791", "lvl": "debug", "msg": "progress  50% (259MB)"}
- {"dt": "2023-03-06T23:53:15.208357", "lvl": "debug", "msg": "progress  55% (285MB)"}
- {"dt": "2023-03-06T23:53:20.780754", "lvl": "debug", "msg": "progress  60% (310MB)"}
- {"dt": "2023-03-06T23:53:29.547426", "lvl": "debug", "msg": "progress  65% (336MB)"}
- {"dt": "2023-03-06T23:53:39.188123", "lvl": "debug", "msg": "progress  70% (362MB)"}
- {"dt": "2023-03-06T23:53:43.360849", "lvl": "debug", "msg": "progress  75% (388MB)"}
- {"dt": "2023-03-06T23:53:46.911597", "lvl": "debug", "msg": "progress  80% (414MB)"}
- {"dt": "2023-03-06T23:53:50.424182", "lvl": "debug", "msg": "progress  85% (440MB)"}
- {"dt": "2023-03-06T23:53:54.285956", "lvl": "debug", "msg": "progress  90% (466MB)"}
- {"dt": "2023-03-06T23:53:58.427212", "lvl": "debug", "msg": "progress  95% (492MB)"}
- {"dt": "2023-03-06T23:54:04.852004", "lvl": "debug", "msg": "progress 100% (518MB)"}
- {"dt": "2023-03-06T23:54:07.372554", "lvl": "info", "msg": "518MB downloaded in 160.99s (3.22MB/s)"}
- {"dt": "2023-03-06T23:54:07.373519", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:02:41) [common]"}
- {"dt": "2023-03-06T23:54:07.373804", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "160.99", "extra": {"label": "rootvd", "md5sum": "23006a2128d99837af3873b222cede30", "sha256sum": "10756ed1be539af43bcb7b05485b672b9a7bcca85de297ea7e420ea8d43e0424", "sha512sum": "f1712fe227d42747d6bc4ac2bf7dc48387c93b373963073f7176627a4aea7ce02b614d2885992d87f6245d1884385483037d4a57da5e57d8b390b7b39a0dc79e", "size": ! "543340368"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-06T23:54:07.374468", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:02:41) [common]"}
- {"dt": "2023-03-06T23:54:07.374767", "lvl": "info", "msg": "end: 1 deployimages (duration 00:03:12) [common]"}
- {"dt": "2023-03-06T23:54:07.375091", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-06T23:54:07.375405", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-06T23:54:07.375708", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-06T23:54:07.376345", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-06T23:54:07.376839", "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-651605 -kernel /var/lib/lava/dispatcher/tmp/32152/deployimages-d9sa8hio/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/32152/deployimages-d9sa8hio/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32152/apply-overlay-guest-_vs7_p6r/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-03-06T23:54:08.261937", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-06T23:54:08.262815", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-06T23:54:08.263093", "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-06T23:54:08.263683", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-06T23:54:08.263984", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-06T23:54:08.264287", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-06T23:54:08.264559", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-06T23:54:08.284440", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-03-06T23:54:08.316969", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-06T23:54:08.317255", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:54:08.317521", "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-06T23:54:08.317972", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-06T23:54:08.318265", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-06T23:54:08.318512", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-06T23:54:08.318767", "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-06T23:54:08.319289", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-06T23:54:08.319637", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32152/deployimages-d9sa8hio/kernel"}
- {"dt": "2023-03-06T23:54:08.322521", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32152/deployimages-d9sa8hio/rootvd"}
- {"dt": "2023-03-06T23:54:08.923858", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-06T23:54:08.926166", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:54:08.926540", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-06T23:54:08.928088", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-06T23:54:08.928584", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-06T23:54:09.029741", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-06T23:54:09.030244", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32152"}
- {"dt": "2023-03-06T23:54:09.050340", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-06T23:54:09.050607", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
