- {"dt": "2023-11-24T22:10:56.020941", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-11-24T22:10:56.021444", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-11-24T22:10:56.021789", "lvl": "info", "msg": "Start time: 2023-11-24 22:10:56.021761+00:00 (UTC)"}
- {"dt": "2023-11-24T22:10:56.025504", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29474/1/qemux86-64/bzImage exists"}
- {"dt": "2023-11-24T22:10:56.233089", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29474/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz exists"}
- {"dt": "2023-11-24T22:10:56.467865", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-11-24T22:10:56.469540", "lvl": "info", "msg": "validate duration: 0.45"}
- {"dt": "2023-11-24T22:10:56.469823", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-11-24T22:10:56.470326", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.470639", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.471214", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol"}
- {"dt": "2023-11-24T22:10:56.471625", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin"}
- {"dt": "2023-11-24T22:10:56.471965", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/tests"}
- {"dt": "2023-11-24T22:10:56.472300", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/results"}
- {"dt": "2023-11-24T22:10:56.472655", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-add-keys"}
- {"dt": "2023-11-24T22:10:56.473117", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-add-sources"}
- {"dt": "2023-11-24T22:10:56.473540", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-background-process-start"}
- {"dt": "2023-11-24T22:10:56.473970", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-background-process-stop"}
- {"dt": "2023-11-24T22:10:56.474378", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-common-functions"}
- {"dt": "2023-11-24T22:10:56.474794", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-echo-ipv4"}
- {"dt": "2023-11-24T22:10:56.475216", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-install-packages"}
- {"dt": "2023-11-24T22:10:56.475629", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-installed-packages"}
- {"dt": "2023-11-24T22:10:56.476046", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-os-build"}
- {"dt": "2023-11-24T22:10:56.476469", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-probe-channel"}
- {"dt": "2023-11-24T22:10:56.476879", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-probe-ip"}
- {"dt": "2023-11-24T22:10:56.477300", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-target-ip"}
- {"dt": "2023-11-24T22:10:56.477738", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-target-mac"}
- {"dt": "2023-11-24T22:10:56.478161", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-target-storage"}
- {"dt": "2023-11-24T22:10:56.478584", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-case"}
- {"dt": "2023-11-24T22:10:56.478997", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-event"}
- {"dt": "2023-11-24T22:10:56.479403", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-feedback"}
- {"dt": "2023-11-24T22:10:56.479815", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-raise"}
- {"dt": "2023-11-24T22:10:56.480254", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-reference"}
- {"dt": "2023-11-24T22:10:56.480676", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-runner"}
- {"dt": "2023-11-24T22:10:56.481089", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-set"}
- {"dt": "2023-11-24T22:10:56.481498", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-test-shell"}
- {"dt": "2023-11-24T22:10:56.481916", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-install-packages (oe)"}
- {"dt": "2023-11-24T22:10:56.482413", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/bin/lava-installed-packages (oe)"}
- {"dt": "2023-11-24T22:10:56.482824", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/environment"}
- {"dt": "2023-11-24T22:10:56.483151", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-11-24T22:10:56.483392", "lvl": "debug", "msg": "- LAVA_JOB_ID=33784"}
- {"dt": "2023-11-24T22:10:56.483617", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.31.0.2"}
- {"dt": "2023-11-24T22:10:56.483944", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.484197", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-11-24T22:10:56.484469", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:56.484759", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.484981", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-11-24T22:10:56.485237", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:56.485510", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.485904", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-11-24T22:10:56.486218", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-11-24T22:10:56.486481", "lvl": "debug", "msg": "Using /lava-33784 at stage 0"}
- {"dt": "2023-11-24T22:10:56.486804", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:10:56.487086", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/0_ci-busybox'"}
- {"dt": "2023-11-24T22:10:57.115800", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/0_ci-busybox"}
- {"dt": "2023-11-24T22:10:57.119877", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-11-24T22:10:57.121173", "lvl": "debug", "msg": "uuid=33784_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-11-24T22:10:57.121608", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:10:57.121881", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.64", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-11-24T22:10:57.122463", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.124646", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.124901", "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": "33784_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.125493", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.128276", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.128539", "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": "33784_1.1.3.1", "uuid": "33784_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.129156", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.131843", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/0_ci-busybox test_uuid 33784_1.1.3.1"}
- {"dt": "2023-11-24T22:10:57.132379", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.132632", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/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": "33784_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.133248", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.133505", "lvl": "debug", "msg": "Using /lava-33784 at stage 0"}
- {"dt": "2023-11-24T22:10:57.133867", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:10:57.134135", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-11-24T22:10:57.604965", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-11-24T22:10:57.609140", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-11-24T22:10:57.610265", "lvl": "debug", "msg": "uuid=33784_1.1.3.5 testdef=None"}
- {"dt": "2023-11-24T22:10:57.610673", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.610955", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.48", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-11-24T22:10:57.611543", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.613470", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.613734", "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": "33784_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.614345", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.616848", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.617119", "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": "33784_1.1.3.5", "uuid": "33784_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.617759", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.620198", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/1_ci-smoke-tests-basic test_uuid 33784_1.1.3.5"}
- {"dt": "2023-11-24T22:10:57.621226", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:57.621492", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/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": "33784_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:57.622113", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:59) [common]"}
- {"dt": "2023-11-24T22:10:57.622379", "lvl": "debug", "msg": "Using /lava-33784 at stage 0"}
- {"dt": "2023-11-24T22:10:57.622749", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:10:57.623025", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/2_ci-service-check'"}
- {"dt": "2023-11-24T22:10:58.102605", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/2_ci-service-check"}
- {"dt": "2023-11-24T22:10:58.106556", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-11-24T22:10:58.107662", "lvl": "debug", "msg": "uuid=33784_1.1.3.9 testdef=None"}
- {"dt": "2023-11-24T22:10:58.108056", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:58.108337", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.49", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-11-24T22:10:58.108909", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-11-24T22:10:58.110823", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:58.111075", "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": "33784_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:58.111666", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-11-24T22:10:58.114201", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:58.114463", "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": "33784_1.1.3.9", "uuid": "33784_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:58.115082", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-11-24T22:10:58.117565", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0/tests/2_ci-service-check test_uuid 33784_1.1.3.9"}
- {"dt": "2023-11-24T22:10:58.117840", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-11-24T22:10:58.118063", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-11-24T22:10:58.118531", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:10:58.118778", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/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": "33784_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:10:58.119390", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:58) [common]"}
- {"dt": "2023-11-24T22:10:58.119644", "lvl": "debug", "msg": "Using /lava-33784 at stage 1"}
- {"dt": "2023-11-24T22:10:58.120000", "lvl": "info", "msg": "Fetching tests from https://git.linaro.org/qa/test-definitions.git"}
- {"dt": "2023-11-24T22:10:58.120283", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.linaro.org/qa/test-definitions.git /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1/tests/3_ci-linaro-ptest'"}
- {"dt": "2023-11-24T22:11:02.656516", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1/tests/3_ci-linaro-ptest"}
- {"dt": "2023-11-24T22:11:02.664073", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1/tests/3_ci-linaro-ptest/automated/linux/ptest/ptest.yaml"}
- {"dt": "2023-11-24T22:11:02.665357", "lvl": "debug", "msg": "uuid=33784_1.1.3.13 testdef=None"}
- {"dt": "2023-11-24T22:11:02.665770", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:05) [common]"}
- {"dt": "2023-11-24T22:11:02.666038", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "4.55", "extra": {"commit": "c5c3faacdd3ecff31c6ac02c1ebd3c607b66d419", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:02.666591", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T22:11:02.668744", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:02.668990", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "uuid": "33784_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:02.669575", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T22:11:02.672372", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:02.672627", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "skipped test-install-overlay": "33784_1.1.3.13", "uuid": "33784_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:02.673239", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T22:11:02.685374", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1/tests/3_ci-linaro-ptest test_uuid 33784_1.1.3.13"}
- {"dt": "2023-11-24T22:11:02.685653", "lvl": "debug", "msg": "TESTS='xmlsec1 libxml2 lua'"}
- {"dt": "2023-11-24T22:11:02.686133", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:02.686377", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1/tests/3_ci-linaro-ptest/run.sh", "from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "uuid": "33784_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:02.686977", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-11-24T22:11:02.687234", "lvl": "debug", "msg": "Using /lava-33784 at stage 2"}
- {"dt": "2023-11-24T22:11:02.687587", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:11:02.687858", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/2/tests/4_pyagl-tests'"}
- {"dt": "2023-11-24T22:11:03.289225", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/2/tests/4_pyagl-tests/test-suites/short-smoke/pyagl-nohw-oneshot.yaml"}
- {"dt": "2023-11-24T22:11:03.290759", "lvl": "debug", "msg": "uuid=33784_1.1.3.17 testdef=None"}
- {"dt": "2023-11-24T22:11:03.291181", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:11:03.291455", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.292040", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.293730", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.293977", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33784_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.294565", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.296749", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.297001", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33784_1.1.3.17", "uuid": "33784_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.297612", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.299690", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/2/tests/4_pyagl-tests test_uuid 33784_1.1.3.17"}
- {"dt": "2023-11-24T22:11:03.300231", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.300484", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/2/tests/4_pyagl-tests/run.sh", "from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33784_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.301115", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.301373", "lvl": "debug", "msg": "Using /lava-33784 at stage 3"}
- {"dt": "2023-11-24T22:11:03.301739", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:11:03.302019", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/3/tests/5_screenshooter'"}
- {"dt": "2023-11-24T22:11:03.783675", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/3/tests/5_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-11-24T22:11:03.785210", "lvl": "debug", "msg": "uuid=33784_1.1.3.21 testdef=None"}
- {"dt": "2023-11-24T22:11:03.785641", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.785932", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.48", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.786522", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.788213", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.788470", "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": "33784_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.789063", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.791157", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.791419", "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": "33784_1.1.3.21", "uuid": "33784_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.792044", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.794089", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/3/tests/5_screenshooter test_uuid 33784_1.1.3.21"}
- {"dt": "2023-11-24T22:11:03.794607", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:03.794862", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/3/tests/5_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33784_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:03.795485", "lvl": "debug", "msg": "start: 1.1.3.25 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-11-24T22:11:03.795744", "lvl": "debug", "msg": "Using /lava-33784 at stage 4"}
- {"dt": "2023-11-24T22:11:03.796126", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-24T22:11:03.796416", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4/tests/6_ci-dumpjournal'"}
- {"dt": "2023-11-24T22:11:04.784153", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4/tests/6_ci-dumpjournal"}
- {"dt": "2023-11-24T22:11:04.788840", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4/tests/6_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-11-24T22:11:04.790090", "lvl": "debug", "msg": "uuid=33784_1.1.3.25 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-11-24T22:11:04.790539", "lvl": "debug", "msg": "end: 1.1.3.25 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:11:04.790837", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.00", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.25", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:04.791444", "lvl": "debug", "msg": "start: 1.1.3.26 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-11-24T22:11:04.793901", "lvl": "debug", "msg": "end: 1.1.3.26 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:04.794175", "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": "33784_1.1.3.25"}, "level": "1.1.3.26", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:04.794799", "lvl": "debug", "msg": "start: 1.1.3.27 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-11-24T22:11:04.802880", "lvl": "debug", "msg": "end: 1.1.3.27 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:04.803172", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "33784_1.1.3.25", "uuid": "33784_1.1.3.25"}, "level": "1.1.3.27", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:04.803807", "lvl": "debug", "msg": "start: 1.1.3.28 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-11-24T22:11:04.815944", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4/tests/6_ci-dumpjournal test_uuid 33784_1.1.3.25"}
- {"dt": "2023-11-24T22:11:04.816515", "lvl": "debug", "msg": "end: 1.1.3.28 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:04.816779", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4/tests/6_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": "33784_1.1.3.25"}, "level": "1.1.3.28", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:04.817377", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-11-24T22:11:04.817618", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/0 for stage 0"}
- {"dt": "2023-11-24T22:11:04.817950", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-11-24T22:11:04.818205", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-11-24T22:11:04.818434", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-11-24T22:11:04.818701", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/1 for stage 1"}
- {"dt": "2023-11-24T22:11:04.819027", "lvl": "debug", "msg": "- 3_ci-linaro-ptest"}
- {"dt": "2023-11-24T22:11:04.819302", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/2 for stage 2"}
- {"dt": "2023-11-24T22:11:04.819624", "lvl": "debug", "msg": "- 4_pyagl-tests"}
- {"dt": "2023-11-24T22:11:04.819909", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/3 for stage 3"}
- {"dt": "2023-11-24T22:11:04.820246", "lvl": "debug", "msg": "- 5_screenshooter"}
- {"dt": "2023-11-24T22:11:04.820521", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33784/lava-overlay-cn3um_ol/lava-33784/4 for stage 4"}
- {"dt": "2023-11-24T22:11:04.820840", "lvl": "debug", "msg": "- 6_ci-dumpjournal"}
- {"dt": "2023-11-24T22:11:04.821180", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:08) [common]"}
- {"dt": "2023-11-24T22:11:04.821491", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-11-24T22:11:22.026813", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:17) [common]"}
- {"dt": "2023-11-24T22:11:22.027640", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:34) [common]"}
- {"dt": "2023-11-24T22:11:22.027961", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:22.028326", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:26) [common]"}
- {"dt": "2023-11-24T22:11:22.028628", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:34) [common]"}
- {"dt": "2023-11-24T22:11:22.028899", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/33784/compress-overlay-4gdf68ee/overlay-1.1.4.tar.gz"}
- {"dt": "2023-11-24T22:11:29.909970", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:08) [common]"}
- {"dt": "2023-11-24T22:11:29.910746", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "7.88", "extra": {"success": "a7ef8077-71fe-4712-902b-75352c827ce8"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:29.911304", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:27) [common]"}
- {"dt": "2023-11-24T22:11:29.911601", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:11:29.911906", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:27) [common]"}
- {"dt": "2023-11-24T22:11:29.912239", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:27) [common]"}
- {"dt": "2023-11-24T22:11:29.912777", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29474/1/qemux86-64/bzImage"}
- {"dt": "2023-11-24T22:11:29.913021", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33784/deployimages-9cwbqvaw/kernel/bzImage"}
- {"dt": "2023-11-24T22:11:29.913241", "lvl": "debug", "msg": "total size: 9028256 (8MB)"}
- {"dt": "2023-11-24T22:11:29.913457", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-24T22:11:30.228232", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-24T22:11:30.637366", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-11-24T22:11:30.740027", "lvl": "debug", "msg": "progress  10% (0MB)"}
- {"dt": "2023-11-24T22:11:30.747534", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-11-24T22:11:30.843122", "lvl": "debug", "msg": "progress  20% (1MB)"}
- {"dt": "2023-11-24T22:11:30.850220", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-11-24T22:11:30.857421", "lvl": "debug", "msg": "progress  30% (2MB)"}
- {"dt": "2023-11-24T22:11:30.945808", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-11-24T22:11:30.952574", "lvl": "debug", "msg": "progress  40% (3MB)"}
- {"dt": "2023-11-24T22:11:30.958808", "lvl": "debug", "msg": "progress  45% (3MB)"}
- {"dt": "2023-11-24T22:11:31.048691", "lvl": "debug", "msg": "progress  50% (4MB)"}
- {"dt": "2023-11-24T22:11:31.056152", "lvl": "debug", "msg": "progress  55% (4MB)"}
- {"dt": "2023-11-24T22:11:31.064019", "lvl": "debug", "msg": "progress  60% (5MB)"}
- {"dt": "2023-11-24T22:11:31.151632", "lvl": "debug", "msg": "progress  65% (5MB)"}
- {"dt": "2023-11-24T22:11:31.158625", "lvl": "debug", "msg": "progress  70% (6MB)"}
- {"dt": "2023-11-24T22:11:31.165847", "lvl": "debug", "msg": "progress  75% (6MB)"}
- {"dt": "2023-11-24T22:11:31.253517", "lvl": "debug", "msg": "progress  80% (6MB)"}
- {"dt": "2023-11-24T22:11:31.260848", "lvl": "debug", "msg": "progress  85% (7MB)"}
- {"dt": "2023-11-24T22:11:31.267485", "lvl": "debug", "msg": "progress  90% (7MB)"}
- {"dt": "2023-11-24T22:11:31.354988", "lvl": "debug", "msg": "progress  95% (8MB)"}
- {"dt": "2023-11-24T22:11:31.362262", "lvl": "debug", "msg": "progress 100% (8MB)"}
- {"dt": "2023-11-24T22:11:31.362813", "lvl": "info", "msg": "8MB downloaded in 1.45s (5.94MB/s)"}
- {"dt": "2023-11-24T22:11:31.363293", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:11:31.363556", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.45", "extra": {"label": "kernel", "md5sum": "7433677027e6a081f50018026a690c10", "sha256sum": "f308c1487b4a1c8fc0312925f629b37a06b831a0422927aa2bfe2393817936a0", "sha512sum": "db6eeeb38ae232801a9b20932437d8b379b03e2be284911352474b3d6e250d2ca63722f76c78ca175dc390a945d9660956fca14c71855bc617ee230751c6273b", "size": ! "9028256"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:11:31.364187", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:11:31.364480", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:25) [common]"}
- {"dt": "2023-11-24T22:11:31.364769", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:25) [common]"}
- {"dt": "2023-11-24T22:11:31.365170", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29474/1/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz"}
- {"dt": "2023-11-24T22:11:31.365410", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33784/deployimages-9cwbqvaw/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-11-24T22:11:31.365629", "lvl": "debug", "msg": "total size: 609608904 (581MB)"}
- {"dt": "2023-11-24T22:11:31.365850", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-11-24T22:11:31.683258", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-24T22:11:37.014153", "lvl": "debug", "msg": "progress   5% (29MB)"}
- {"dt": "2023-11-24T22:11:40.672130", "lvl": "debug", "msg": "progress  10% (58MB)"}
- {"dt": "2023-11-24T22:11:50.101137", "lvl": "debug", "msg": "progress  15% (87MB)"}
- {"dt": "2023-11-24T22:11:54.767947", "lvl": "debug", "msg": "progress  20% (116MB)"}
- {"dt": "2023-11-24T22:11:59.169321", "lvl": "debug", "msg": "progress  25% (145MB)"}
- {"dt": "2023-11-24T22:12:02.870473", "lvl": "debug", "msg": "progress  30% (174MB)"}
- {"dt": "2023-11-24T22:12:06.543503", "lvl": "debug", "msg": "progress  35% (203MB)"}
- {"dt": "2023-11-24T22:12:10.614608", "lvl": "debug", "msg": "progress  40% (232MB)"}
- {"dt": "2023-11-24T22:12:14.949678", "lvl": "debug", "msg": "progress  45% (261MB)"}
- {"dt": "2023-11-24T22:12:19.860898", "lvl": "debug", "msg": "progress  50% (290MB)"}
- {"dt": "2023-11-24T22:12:24.259986", "lvl": "debug", "msg": "progress  55% (319MB)"}
- {"dt": "2023-11-24T22:12:28.646461", "lvl": "debug", "msg": "progress  60% (348MB)"}
- {"dt": "2023-11-24T22:12:33.177467", "lvl": "debug", "msg": "progress  65% (377MB)"}
- {"dt": "2023-11-24T22:12:38.760367", "lvl": "debug", "msg": "progress  70% (406MB)"}
- {"dt": "2023-11-24T22:12:42.972820", "lvl": "debug", "msg": "progress  75% (436MB)"}
- {"dt": "2023-11-24T22:12:47.420303", "lvl": "debug", "msg": "progress  80% (465MB)"}
- {"dt": "2023-11-24T22:12:51.335271", "lvl": "debug", "msg": "progress  85% (494MB)"}
- {"dt": "2023-11-24T22:12:55.346865", "lvl": "debug", "msg": "progress  90% (523MB)"}
- {"dt": "2023-11-24T22:12:59.411933", "lvl": "debug", "msg": "progress  95% (552MB)"}
- {"dt": "2023-11-24T22:13:05.047177", "lvl": "debug", "msg": "progress 100% (581MB)"}
- {"dt": "2023-11-24T22:13:07.548988", "lvl": "info", "msg": "581MB downloaded in 96.18s (6.04MB/s)"}
- {"dt": "2023-11-24T22:13:07.549830", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:36) [common]"}
- {"dt": "2023-11-24T22:13:07.550109", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "96.19", "extra": {"label": "rootvd", "md5sum": "66a4ba8a472a8f425a6335a833110d38", "sha256sum": "79aae5e5750396303af551e4f24632b307b185d72b92cd9e3c447b2c82a43892", "sha512sum": "f0c78c72ac7c3595139a2e54f582e5da1fe3f76a84f0b6cf871350f7241d1b4f2879e6575b63844414162a7f454518fa20e007e386002ec79730f060f5508ee7", "size": ! "609608904"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-24T22:13:07.550761", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:36) [common]"}
- {"dt": "2023-11-24T22:13:07.551061", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:11) [common]"}
- {"dt": "2023-11-24T22:13:07.551368", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T22:13:07.551666", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T22:13:07.551949", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-11-24T22:13:07.552667", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-11-24T22:13:07.553140", "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-130892 -kernel /var/lib/lava/dispatcher/tmp/33784/deployimages-9cwbqvaw/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/33784/deployimages-9cwbqvaw/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/33784/apply-overlay-guest-b648z5w0/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-11-24T22:13:08.437271", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-11-24T22:13:08.438162", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:13:08.438442", "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-11-24T22:13:08.439021", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:13:08.439327", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-11-24T22:13:08.439635", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-11-24T22:13:08.439887", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-11-24T22:13:08.458995", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-11-24T22:13:08.476408", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-11-24T22:13:08.476679", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:13:08.476940", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.04", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-24T22:13:08.477389", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-11-24T22:13:08.477671", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-11-24T22:13:08.477910", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-11-24T22:13:08.478149", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.93", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-24T22:13:08.478679", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-11-24T22:13:08.479041", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33784/deployimages-9cwbqvaw/kernel"}
- {"dt": "2023-11-24T22:13:08.481348", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33784/deployimages-9cwbqvaw/rootvd"}
- {"dt": "2023-11-24T22:13:09.001854", "lvl": "debug", "msg": "start: 8.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-11-24T22:13:09.002314", "lvl": "debug", "msg": "end: 8.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:13:09.002625", "lvl": "debug", "msg": "start: 8.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-11-24T22:13:09.003225", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-11-24T22:13:09.003599", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-11-24T22:13:09.104571", "lvl": "debug", "msg": "end: 8.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-11-24T22:13:09.104883", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33784"}
- {"dt": "2023-11-24T22:13:09.214154", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-11-24T22:13:09.214420", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
