- {"dt": "2023-03-15T15:30:53.711461", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-15T15:30:53.711934", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-15T15:30:53.712229", "lvl": "info", "msg": "Start time: 2023-03-15 15:30:53.712212+00:00 (UTC)"}
- {"dt": "2023-03-15T15:30:53.714775", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28570/1/qemux86-64/bzImage exists"}
- {"dt": "2023-03-15T15:30:53.992601", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28570/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz exists"}
- {"dt": "2023-03-15T15:30:54.280776", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-15T15:30:54.282689", "lvl": "info", "msg": "validate duration: 0.57"}
- {"dt": "2023-03-15T15:30:54.283011", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-15T15:30:54.283595", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.283955", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.284570", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d"}
- {"dt": "2023-03-15T15:30:54.285051", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin"}
- {"dt": "2023-03-15T15:30:54.285424", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/tests"}
- {"dt": "2023-03-15T15:30:54.285785", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/results"}
- {"dt": "2023-03-15T15:30:54.286205", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-add-keys"}
- {"dt": "2023-03-15T15:30:54.286723", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-add-sources"}
- {"dt": "2023-03-15T15:30:54.287194", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-background-process-start"}
- {"dt": "2023-03-15T15:30:54.287710", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-background-process-stop"}
- {"dt": "2023-03-15T15:30:54.288183", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-common-functions"}
- {"dt": "2023-03-15T15:30:54.288663", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-echo-ipv4"}
- {"dt": "2023-03-15T15:30:54.289126", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-install-packages"}
- {"dt": "2023-03-15T15:30:54.289591", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-installed-packages"}
- {"dt": "2023-03-15T15:30:54.290061", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-os-build"}
- {"dt": "2023-03-15T15:30:54.290521", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-probe-channel"}
- {"dt": "2023-03-15T15:30:54.291010", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-probe-ip"}
- {"dt": "2023-03-15T15:30:54.291504", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-target-ip"}
- {"dt": "2023-03-15T15:30:54.291999", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-target-mac"}
- {"dt": "2023-03-15T15:30:54.292460", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-target-storage"}
- {"dt": "2023-03-15T15:30:54.292936", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-case"}
- {"dt": "2023-03-15T15:30:54.293393", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-event"}
- {"dt": "2023-03-15T15:30:54.293856", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-feedback"}
- {"dt": "2023-03-15T15:30:54.294318", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-raise"}
- {"dt": "2023-03-15T15:30:54.294785", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-reference"}
- {"dt": "2023-03-15T15:30:54.295249", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-runner"}
- {"dt": "2023-03-15T15:30:54.295741", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-set"}
- {"dt": "2023-03-15T15:30:54.296209", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-test-shell"}
- {"dt": "2023-03-15T15:30:54.296677", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-15T15:30:54.297213", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-15T15:30:54.297670", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/environment"}
- {"dt": "2023-03-15T15:30:54.298046", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-15T15:30:54.298326", "lvl": "debug", "msg": "- LAVA_JOB_ID=32237"}
- {"dt": "2023-03-15T15:30:54.298583", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.111.1"}
- {"dt": "2023-03-15T15:30:54.298967", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.299233", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-15T15:30:54.299535", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:54.299882", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.300135", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-15T15:30:54.300418", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:54.300718", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.301206", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-15T15:30:54.301572", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T15:30:54.301864", "lvl": "debug", "msg": "Using /lava-32237 at stage 0"}
- {"dt": "2023-03-15T15:30:54.302256", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T15:30:54.302579", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/0_ci-busybox'"}
- {"dt": "2023-03-15T15:30:55.974955", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/0_ci-busybox"}
- {"dt": "2023-03-15T15:30:55.977746", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-15T15:30:55.978925", "lvl": "debug", "msg": "uuid=32237_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-15T15:30:55.979325", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T15:30:55.979585", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.68", "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-15T15:30:55.980111", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T15:30:55.982112", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:55.982349", "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": "32237_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:55.982893", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T15:30:55.985519", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:55.985778", "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": "32237_1.1.3.1", "uuid": "32237_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:55.986350", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T15:30:55.988871", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/0_ci-busybox test_uuid 32237_1.1.3.1"}
- {"dt": "2023-03-15T15:30:55.989374", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:55.989611", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/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": "32237_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:55.990185", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T15:30:55.990415", "lvl": "debug", "msg": "Using /lava-32237 at stage 0"}
- {"dt": "2023-03-15T15:30:55.990744", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T15:30:55.990988", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-03-15T15:30:57.518255", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T15:30:57.520978", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-15T15:30:57.521984", "lvl": "debug", "msg": "uuid=32237_1.1.3.5 testdef=None"}
- {"dt": "2023-03-15T15:30:57.522350", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T15:30:57.522590", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.53", "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-15T15:30:57.523099", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T15:30:57.524847", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:57.525082", "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": "32237_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:57.525631", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T15:30:57.527908", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:57.528161", "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": "32237_1.1.3.5", "uuid": "32237_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:57.528751", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T15:30:57.530935", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/1_ci-smoke-tests-basic test_uuid 32237_1.1.3.5"}
- {"dt": "2023-03-15T15:30:57.531903", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:57.532163", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/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": "32237_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:57.532744", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-03-15T15:30:57.532978", "lvl": "debug", "msg": "Using /lava-32237 at stage 0"}
- {"dt": "2023-03-15T15:30:57.533316", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T15:30:57.533561", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/2_ci-service-check'"}
- {"dt": "2023-03-15T15:30:59.298607", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/2_ci-service-check"}
- {"dt": "2023-03-15T15:30:59.301436", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-15T15:30:59.302535", "lvl": "debug", "msg": "uuid=32237_1.1.3.9 testdef=None"}
- {"dt": "2023-03-15T15:30:59.302936", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T15:30:59.303189", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.77", "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-15T15:30:59.303778", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-15T15:30:59.305627", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:59.305882", "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": "32237_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:59.306461", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-15T15:30:59.309117", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:59.309381", "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": "32237_1.1.3.9", "uuid": "32237_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:59.309968", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-15T15:30:59.312331", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0/tests/2_ci-service-check test_uuid 32237_1.1.3.9"}
- {"dt": "2023-03-15T15:30:59.312600", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-15T15:30:59.312806", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-15T15:30:59.313241", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:30:59.313469", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/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": "32237_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:30:59.314042", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-15T15:30:59.314275", "lvl": "debug", "msg": "Using /lava-32237 at stage 1"}
- {"dt": "2023-03-15T15:30:59.314604", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T15:30:59.314848", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/1/tests/3_screenshooter'"}
- {"dt": "2023-03-15T15:31:00.945476", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/1/tests/3_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-15T15:31:00.946797", "lvl": "debug", "msg": "uuid=32237_1.1.3.13 testdef=None"}
- {"dt": "2023-03-15T15:31:00.947207", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T15:31:00.947471", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.63", "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-15T15:31:00.948047", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T15:31:00.949581", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:00.949823", "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": "32237_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:00.950384", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T15:31:00.952391", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:00.952649", "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": "32237_1.1.3.13", "uuid": "32237_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:00.953233", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T15:31:00.955139", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/1/tests/3_screenshooter test_uuid 32237_1.1.3.13"}
- {"dt": "2023-03-15T15:31:00.955665", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:00.955914", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/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": "32237_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:00.956511", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-15T15:31:00.956757", "lvl": "debug", "msg": "Using /lava-32237 at stage 2"}
- {"dt": "2023-03-15T15:31:00.957109", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T15:31:00.957358", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-15T15:31:02.602826", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-15T15:31:02.606843", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-15T15:31:02.608485", "lvl": "debug", "msg": "uuid=32237_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-15T15:31:02.609065", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T15:31:02.609433", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.65", "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-15T15:31:02.610191", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T15:31:02.612994", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:02.613342", "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": "32237_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:02.614134", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T15:31:02.617788", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:02.618154", "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": "32237_1.1.3.17", "uuid": "32237_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:02.618999", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T15:31:02.622712", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/2/tests/4_ci-dumpjournal test_uuid 32237_1.1.3.17"}
- {"dt": "2023-03-15T15:31:02.623427", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:02.623810", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/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": "32237_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:02.624584", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-15T15:31:02.624899", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/0 for stage 0"}
- {"dt": "2023-03-15T15:31:02.625325", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-03-15T15:31:02.625663", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T15:31:02.625954", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-03-15T15:31:02.626287", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/1 for stage 1"}
- {"dt": "2023-03-15T15:31:02.626684", "lvl": "debug", "msg": "- 3_screenshooter"}
- {"dt": "2023-03-15T15:31:02.627027", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32237/lava-overlay-kcgj5h5d/lava-32237/2 for stage 2"}
- {"dt": "2023-03-15T15:31:02.627419", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-15T15:31:02.627959", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:08) [common]"}
- {"dt": "2023-03-15T15:31:02.628346", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T15:31:02.851945", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:02.852289", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-15T15:31:02.852537", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:02.852806", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2023-03-15T15:31:02.853048", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:51) [common]"}
- {"dt": "2023-03-15T15:31:02.853262", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32237/compress-overlay-t0uwaw7d/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-15T15:31:07.238038", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-15T15:31:07.238683", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "4.38", "extra": {"success": "c047530a-1fa1-430d-b784-4c82ef318156"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:07.239163", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:47) [common]"}
- {"dt": "2023-03-15T15:31:07.239417", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:31:07.239721", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:47) [common]"}
- {"dt": "2023-03-15T15:31:07.240035", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:47) [common]"}
- {"dt": "2023-03-15T15:31:07.240522", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28570/1/qemux86-64/bzImage"}
- {"dt": "2023-03-15T15:31:07.240755", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32237/deployimages-rk2nslvg/kernel/bzImage"}
- {"dt": "2023-03-15T15:31:07.240970", "lvl": "debug", "msg": "total size: 10508416 (10MB)"}
- {"dt": "2023-03-15T15:31:07.241177", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-15T15:31:07.685802", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T15:31:08.114982", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-15T15:31:08.223751", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-03-15T15:31:08.244292", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-03-15T15:31:08.336945", "lvl": "debug", "msg": "progress  20% (2MB)"}
- {"dt": "2023-03-15T15:31:08.358414", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-03-15T15:31:08.378894", "lvl": "debug", "msg": "progress  30% (3MB)"}
- {"dt": "2023-03-15T15:31:08.432953", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-03-15T15:31:08.453483", "lvl": "debug", "msg": "progress  40% (4MB)"}
- {"dt": "2023-03-15T15:31:08.475127", "lvl": "debug", "msg": "progress  45% (4MB)"}
- {"dt": "2023-03-15T15:31:08.495532", "lvl": "debug", "msg": "progress  50% (5MB)"}
- {"dt": "2023-03-15T15:31:08.516237", "lvl": "debug", "msg": "progress  55% (5MB)"}
- {"dt": "2023-03-15T15:31:08.536876", "lvl": "debug", "msg": "progress  60% (6MB)"}
- {"dt": "2023-03-15T15:31:08.557194", "lvl": "debug", "msg": "progress  65% (6MB)"}
- {"dt": "2023-03-15T15:31:08.578260", "lvl": "debug", "msg": "progress  70% (7MB)"}
- {"dt": "2023-03-15T15:31:08.598544", "lvl": "debug", "msg": "progress  75% (7MB)"}
- {"dt": "2023-03-15T15:31:08.619343", "lvl": "debug", "msg": "progress  80% (8MB)"}
- {"dt": "2023-03-15T15:31:08.644193", "lvl": "debug", "msg": "progress  85% (8MB)"}
- {"dt": "2023-03-15T15:31:08.664774", "lvl": "debug", "msg": "progress  90% (9MB)"}
- {"dt": "2023-03-15T15:31:08.685423", "lvl": "debug", "msg": "progress  95% (9MB)"}
- {"dt": "2023-03-15T15:31:08.706084", "lvl": "debug", "msg": "progress 100% (10MB)"}
- {"dt": "2023-03-15T15:31:08.707316", "lvl": "info", "msg": "10MB downloaded in 1.47s (6.83MB/s)"}
- {"dt": "2023-03-15T15:31:08.708184", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T15:31:08.708671", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.47", "extra": {"label": "kernel", "md5sum": "49eb89d0933b1915369e6570a3821e29", "sha256sum": "a60cf9778dc9e4e2009286cec4cb9727dc8cb3be247a8832af25bc01ac18e5d6", "sha512sum": "9875bfd3daf5909de70443563fff546fde91b5ddae9a4c2fb95f9f10724bdca6e86ac05c9659f30df97907f6a60dad92687dd913d79468eff51336c86f38d2b2", "size": ! "10508416"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:31:08.709846", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T15:31:08.710391", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:46) [common]"}
- {"dt": "2023-03-15T15:31:08.710926", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:46) [common]"}
- {"dt": "2023-03-15T15:31:08.711665", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28570/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz"}
- {"dt": "2023-03-15T15:31:08.712076", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32237/deployimages-rk2nslvg/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-03-15T15:31:08.712441", "lvl": "debug", "msg": "total size: 542539500 (517MB)"}
- {"dt": "2023-03-15T15:31:08.712809", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-15T15:31:09.091174", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T15:31:12.330208", "lvl": "debug", "msg": "progress   5% (25MB)"}
- {"dt": "2023-03-15T15:31:15.667850", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-03-15T15:31:19.092901", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-03-15T15:31:22.143787", "lvl": "debug", "msg": "progress  20% (103MB)"}
- {"dt": "2023-03-15T15:31:25.558076", "lvl": "debug", "msg": "progress  25% (129MB)"}
- {"dt": "2023-03-15T15:31:28.950256", "lvl": "debug", "msg": "progress  30% (155MB)"}
- {"dt": "2023-03-15T15:31:32.793894", "lvl": "debug", "msg": "progress  35% (181MB)"}
- {"dt": "2023-03-15T15:31:37.074970", "lvl": "debug", "msg": "progress  40% (206MB)"}
- {"dt": "2023-03-15T15:31:40.911829", "lvl": "debug", "msg": "progress  45% (232MB)"}
- {"dt": "2023-03-15T15:31:44.232000", "lvl": "debug", "msg": "progress  50% (258MB)"}
- {"dt": "2023-03-15T15:31:47.478165", "lvl": "debug", "msg": "progress  55% (284MB)"}
- {"dt": "2023-03-15T15:31:51.420644", "lvl": "debug", "msg": "progress  60% (310MB)"}
- {"dt": "2023-03-15T15:31:55.118908", "lvl": "debug", "msg": "progress  65% (336MB)"}
- {"dt": "2023-03-15T15:31:58.955001", "lvl": "debug", "msg": "progress  70% (362MB)"}
- {"dt": "2023-03-15T15:32:02.603586", "lvl": "debug", "msg": "progress  75% (388MB)"}
- {"dt": "2023-03-15T15:32:05.824661", "lvl": "debug", "msg": "progress  80% (413MB)"}
- {"dt": "2023-03-15T15:32:09.028506", "lvl": "debug", "msg": "progress  85% (439MB)"}
- {"dt": "2023-03-15T15:32:12.312297", "lvl": "debug", "msg": "progress  90% (465MB)"}
- {"dt": "2023-03-15T15:32:15.442576", "lvl": "debug", "msg": "progress  95% (491MB)"}
- {"dt": "2023-03-15T15:32:20.428318", "lvl": "debug", "msg": "progress 100% (517MB)"}
- {"dt": "2023-03-15T15:32:22.736453", "lvl": "info", "msg": "517MB downloaded in 74.02s (6.99MB/s)"}
- {"dt": "2023-03-15T15:32:22.737212", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:14) [common]"}
- {"dt": "2023-03-15T15:32:22.737500", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "74.03", "extra": {"label": "rootvd", "md5sum": "08cd2c2514a61a1d9c6ff85a59b71253", "sha256sum": "7b572ee75dd683d0268dddd36cd8341a1b4d6a3a275ce9bc6d36db06a371e216", "sha512sum": "c555136664ef377394a29d55525ceddb83e58c36f8490c73b1e69667aa7758095658e2206e580d23ececcd1980dc5c1a18c7055a0e9418692c14e29f7e476d9e", "size": ! "542539500"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T15:32:22.738106", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:14) [common]"}
- {"dt": "2023-03-15T15:32:22.738383", "lvl": "info", "msg": "end: 1 deployimages (duration 00:01:28) [common]"}
- {"dt": "2023-03-15T15:32:22.738703", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T15:32:22.738978", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T15:32:22.739237", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T15:32:22.739586", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-15T15:32:22.739976", "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-667476 -kernel /var/lib/lava/dispatcher/tmp/32237/deployimages-rk2nslvg/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/32237/deployimages-rk2nslvg/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32237/apply-overlay-guest-76api8ot/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-03-15T15:32:23.540344", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-15T15:32:23.541240", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T15:32:23.541516", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.80", "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-15T15:32:23.542036", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T15:32:23.542300", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-15T15:32:23.542553", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-15T15:32:23.542770", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-15T15:32:23.569864", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-03-15T15:32:23.571269", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T15:32:23.571571", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:32:23.571840", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.03", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-15T15:32:23.572257", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-15T15:32:23.572531", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T15:32:23.572759", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T15:32:23.573001", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.83", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-15T15:32:23.573494", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-15T15:32:23.573812", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32237/deployimages-rk2nslvg/kernel"}
- {"dt": "2023-03-15T15:32:23.574542", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32237/deployimages-rk2nslvg/rootvd"}
- {"dt": "2023-03-15T15:32:23.946982", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-15T15:32:23.947371", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:32:23.947692", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-15T15:32:23.948226", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-15T15:32:23.948579", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-15T15:32:24.049555", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T15:32:24.049895", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32237"}
- {"dt": "2023-03-15T15:32:24.064439", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-15T15:32:24.064722", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
