- {"dt": "2023-03-02T07:27:31.498168", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-02T07:27:31.499560", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-02T07:27:31.500489", "lvl": "info", "msg": "Start time: 2023-03-02 07:27:31.500435+00:00 (UTC)"}
- {"dt": "2023-03-02T07:27:31.501572", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/initramfs-netboot-image-bbe.ext4.gz exists"}
- {"dt": "2023-03-02T07:27:31.720257", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/zImage exists"}
- {"dt": "2023-03-02T07:27:31.925762", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/am335x-sancloud-bbe.dtb exists"}
- {"dt": "2023-03-02T07:27:32.133855", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz exists"}
- {"dt": "2023-03-02T07:27:32.345452", "lvl": "info", "msg": "validate duration: 0.85"}
- {"dt": "2023-03-02T07:27:32.346249", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-02T07:27:32.347633", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:27:32.348295", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-03-02T07:27:32.348807", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-03-02T07:27:32.349252", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-03-02T07:27:32.350136", "lvl": "debug", "msg": "Set_port 61977"}
- {"dt": "2023-03-02T07:27:32.351721", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:27:32.352608", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:27:32.353721", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:27:32.354354", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:27:32.354848", "lvl": "debug", "msg": "total size: 1677702 (1MB)"}
- {"dt": "2023-03-02T07:27:32.355380", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:27:32.661073", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-03-02T07:27:32.856086", "lvl": "debug", "msg": "progress   7% (0MB)"}
- {"dt": "2023-03-02T07:27:32.953443", "lvl": "debug", "msg": "progress  13% (0MB)"}
- {"dt": "2023-03-02T07:27:32.963726", "lvl": "debug", "msg": "progress  19% (0MB)"}
- {"dt": "2023-03-02T07:27:32.968206", "lvl": "debug", "msg": "progress  25% (0MB)"}
- {"dt": "2023-03-02T07:27:33.057837", "lvl": "debug", "msg": "progress  31% (0MB)"}
- {"dt": "2023-03-02T07:27:33.063041", "lvl": "debug", "msg": "progress  37% (0MB)"}
- {"dt": "2023-03-02T07:27:33.067453", "lvl": "debug", "msg": "progress  42% (0MB)"}
- {"dt": "2023-03-02T07:27:33.071892", "lvl": "debug", "msg": "progress  48% (0MB)"}
- {"dt": "2023-03-02T07:27:33.156713", "lvl": "debug", "msg": "progress  54% (0MB)"}
- {"dt": "2023-03-02T07:27:33.161654", "lvl": "debug", "msg": "progress  60% (0MB)"}
- {"dt": "2023-03-02T07:27:33.166075", "lvl": "debug", "msg": "progress  66% (1MB)"}
- {"dt": "2023-03-02T07:27:33.170473", "lvl": "debug", "msg": "progress  72% (1MB)"}
- {"dt": "2023-03-02T07:27:33.174860", "lvl": "debug", "msg": "progress  78% (1MB)"}
- {"dt": "2023-03-02T07:27:33.179716", "lvl": "debug", "msg": "progress  83% (1MB)"}
- {"dt": "2023-03-02T07:27:33.184175", "lvl": "debug", "msg": "progress  89% (1MB)"}
- {"dt": "2023-03-02T07:27:33.188574", "lvl": "debug", "msg": "progress  95% (1MB)"}
- {"dt": "2023-03-02T07:27:33.193288", "lvl": "debug", "msg": "progress 100% (1MB)"}
- {"dt": "2023-03-02T07:27:33.194528", "lvl": "info", "msg": "1MB downloaded in 0.84s (1.91MB/s)"}
- {"dt": "2023-03-02T07:27:33.195760", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:33.196443", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.84", "extra": {"label": "initrd", "md5sum": "03a5424f8f6870d2105eb17a3588ed26", "sha256sum": "a3a6be31d9d6269705287fa1479d139058dab50dcbc2bf22b82b66b7a4f70a51", "sha512sum": "ac5da6305d1cb88a4a25061eb7a9c3892c09b5f3ac25a72a811c0e32e63291d4ca223934b1d17de104b8d6583b66da85094df811b59acf85fa5c8d76e408e026", "size": ! "1677702"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:27:33.197896", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:33.198695", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-03-02T07:27:33.199574", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-03-02T07:27:33.200671", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/zImage"}
- {"dt": "2023-03-02T07:27:33.201357", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/kernel/zImage"}
- {"dt": "2023-03-02T07:27:33.201918", "lvl": "debug", "msg": "total size: 4736736 (4MB)"}
- {"dt": "2023-03-02T07:27:33.202462", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:27:33.507298", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T07:27:33.820841", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-02T07:27:34.012693", "lvl": "debug", "msg": "progress  10% (0MB)"}
- {"dt": "2023-03-02T07:27:34.025236", "lvl": "debug", "msg": "progress  15% (0MB)"}
- {"dt": "2023-03-02T07:27:34.109745", "lvl": "debug", "msg": "progress  20% (0MB)"}
- {"dt": "2023-03-02T07:27:34.126053", "lvl": "debug", "msg": "progress  25% (1MB)"}
- {"dt": "2023-03-02T07:27:34.135117", "lvl": "debug", "msg": "progress  30% (1MB)"}
- {"dt": "2023-03-02T07:27:34.144136", "lvl": "debug", "msg": "progress  35% (1MB)"}
- {"dt": "2023-03-02T07:27:34.209677", "lvl": "debug", "msg": "progress  40% (1MB)"}
- {"dt": "2023-03-02T07:27:34.226373", "lvl": "debug", "msg": "progress  45% (2MB)"}
- {"dt": "2023-03-02T07:27:34.235446", "lvl": "debug", "msg": "progress  50% (2MB)"}
- {"dt": "2023-03-02T07:27:34.244460", "lvl": "debug", "msg": "progress  55% (2MB)"}
- {"dt": "2023-03-02T07:27:34.313973", "lvl": "debug", "msg": "progress  60% (2MB)"}
- {"dt": "2023-03-02T07:27:34.325619", "lvl": "debug", "msg": "progress  65% (2MB)"}
- {"dt": "2023-03-02T07:27:34.335801", "lvl": "debug", "msg": "progress  70% (3MB)"}
- {"dt": "2023-03-02T07:27:34.344851", "lvl": "debug", "msg": "progress  75% (3MB)"}
- {"dt": "2023-03-02T07:27:34.410212", "lvl": "debug", "msg": "progress  80% (3MB)"}
- {"dt": "2023-03-02T07:27:34.423796", "lvl": "debug", "msg": "progress  85% (3MB)"}
- {"dt": "2023-03-02T07:27:34.433942", "lvl": "debug", "msg": "progress  90% (4MB)"}
- {"dt": "2023-03-02T07:27:34.443003", "lvl": "debug", "msg": "progress  95% (4MB)"}
- {"dt": "2023-03-02T07:27:34.507358", "lvl": "debug", "msg": "progress 100% (4MB)"}
- {"dt": "2023-03-02T07:27:34.508937", "lvl": "info", "msg": "4MB downloaded in 1.31s (3.46MB/s)"}
- {"dt": "2023-03-02T07:27:34.510087", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:34.510757", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.31", "extra": {"label": "kernel", "md5sum": "1d7f3c8e7474296383f48951f7dac4fd", "sha256sum": "066d251ab16ecd410fcb6224cf46ad812bc766822e97888e1055b296fc028bde", "sha512sum": "371131619ac68d1bc4dea4295b65ab21ace43e54b6c4188cba80e2a3a14aa077d6df1057fbf2db67907fb02931e895cde1e91672030222e896e6e92276ae1701", "size": ! "4736736"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:27:34.512370", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:34.513174", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T07:27:34.513969", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:58) [common]"}
- {"dt": "2023-03-02T07:27:34.515082", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:27:34.515781", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:27:34.516351", "lvl": "debug", "msg": "total size: 93513 (0MB)"}
- {"dt": "2023-03-02T07:27:34.516903", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:27:34.818989", "lvl": "debug", "msg": "progress  35% (0MB)"}
- {"dt": "2023-03-02T07:27:34.915850", "lvl": "debug", "msg": "progress  70% (0MB)"}
- {"dt": "2023-03-02T07:27:35.013058", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-03-02T07:27:35.014972", "lvl": "info", "msg": "0MB downloaded in 0.50s (0.18MB/s)"}
- {"dt": "2023-03-02T07:27:35.016102", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:35.016700", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.50", "extra": {"label": "dtb", "md5sum": "a750fd04e6952e022ccb214b1ce52d5d", "sha256sum": "da338a9ca99d5c9d3d8a8a9a394c3e23604018ea7da64b8017004785ffe6c9db", "sha512sum": "cb4cbc0aab541d48c1ca95ae7d100c64fb29b78de6032a8552eb292568d9459d7f247676dd795e26e6c48da46d904a300dde908eab311c1ffec09fe6e77b6435", "size": ! "93513"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:27:35.018036", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:27:35.018680", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:27:35.020109", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:27:35.021139", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28547/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz"}
- {"dt": "2023-03-02T07:27:35.021757", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:27:35.022215", "lvl": "debug", "msg": "total size: 246957432 (235MB)"}
- {"dt": "2023-03-02T07:27:35.022666", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-02T07:27:35.349226", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T07:27:38.422548", "lvl": "debug", "msg": "progress   5% (11MB)"}
- {"dt": "2023-03-02T07:27:40.908814", "lvl": "debug", "msg": "progress  10% (23MB)"}
- {"dt": "2023-03-02T07:27:43.443368", "lvl": "debug", "msg": "progress  15% (35MB)"}
- {"dt": "2023-03-02T07:27:46.240904", "lvl": "debug", "msg": "progress  20% (47MB)"}
- {"dt": "2023-03-02T07:27:48.507771", "lvl": "debug", "msg": "progress  25% (58MB)"}
- {"dt": "2023-03-02T07:27:50.376966", "lvl": "debug", "msg": "progress  30% (70MB)"}
- {"dt": "2023-03-02T07:27:52.666219", "lvl": "debug", "msg": "progress  35% (82MB)"}
- {"dt": "2023-03-02T07:27:55.986373", "lvl": "debug", "msg": "progress  40% (94MB)"}
- {"dt": "2023-03-02T07:27:59.148456", "lvl": "debug", "msg": "progress  45% (106MB)"}
- {"dt": "2023-03-02T07:28:02.199252", "lvl": "debug", "msg": "progress  50% (117MB)"}
- {"dt": "2023-03-02T07:28:05.598461", "lvl": "debug", "msg": "progress  55% (129MB)"}
- {"dt": "2023-03-02T07:28:08.174667", "lvl": "debug", "msg": "progress  60% (141MB)"}
- {"dt": "2023-03-02T07:28:10.616326", "lvl": "debug", "msg": "progress  65% (153MB)"}
- {"dt": "2023-03-02T07:28:13.476230", "lvl": "debug", "msg": "progress  70% (164MB)"}
- {"dt": "2023-03-02T07:28:16.690626", "lvl": "debug", "msg": "progress  75% (176MB)"}
- {"dt": "2023-03-02T07:28:19.772944", "lvl": "debug", "msg": "progress  80% (188MB)"}
- {"dt": "2023-03-02T07:28:22.958668", "lvl": "debug", "msg": "progress  85% (200MB)"}
- {"dt": "2023-03-02T07:28:25.349337", "lvl": "debug", "msg": "progress  90% (211MB)"}
- {"dt": "2023-03-02T07:28:28.127307", "lvl": "debug", "msg": "progress  95% (223MB)"}
- {"dt": "2023-03-02T07:28:32.524903", "lvl": "debug", "msg": "progress 100% (235MB)"}
- {"dt": "2023-03-02T07:28:36.284265", "lvl": "info", "msg": "235MB downloaded in 61.26s (3.84MB/s)"}
- {"dt": "2023-03-02T07:28:36.286368", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:01) [common]"}
- {"dt": "2023-03-02T07:28:36.287322", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "61.27", "extra": {"label": "nbdroot", "md5sum": "23612822f203daa7f16be12813267be8", "sha256sum": "ab77dfb1933c4da14d2806a6bbe21e6185122b07e02f7ceebc48bf072a77db6f", "sha512sum": "1484895697fe67166201f400fce66ed71fb395de728714027b52819d1f74301b4dc4d6132616d59089c68e770ebf120f2ac3923b6c472897c9a11d23473c3b26", "size": ! "246957432"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:36.289518", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:01) [common]"}
- {"dt": "2023-03-02T07:28:36.290589", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.292573", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87"}
- {"dt": "2023-03-02T07:28:36.294130", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin"}
- {"dt": "2023-03-02T07:28:36.295483", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/tests"}
- {"dt": "2023-03-02T07:28:36.296538", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/results"}
- {"dt": "2023-03-02T07:28:36.297556", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-add-keys"}
- {"dt": "2023-03-02T07:28:36.299020", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-add-sources"}
- {"dt": "2023-03-02T07:28:36.300575", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-background-process-start"}
- {"dt": "2023-03-02T07:28:36.302122", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-background-process-stop"}
- {"dt": "2023-03-02T07:28:36.303739", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-common-functions"}
- {"dt": "2023-03-02T07:28:36.305281", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-echo-ipv4"}
- {"dt": "2023-03-02T07:28:36.306809", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-install-packages"}
- {"dt": "2023-03-02T07:28:36.308376", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-installed-packages"}
- {"dt": "2023-03-02T07:28:36.309976", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-os-build"}
- {"dt": "2023-03-02T07:28:36.311645", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-probe-channel"}
- {"dt": "2023-03-02T07:28:36.313323", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-probe-ip"}
- {"dt": "2023-03-02T07:28:36.315555", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-target-ip"}
- {"dt": "2023-03-02T07:28:36.317259", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-target-mac"}
- {"dt": "2023-03-02T07:28:36.318819", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-target-storage"}
- {"dt": "2023-03-02T07:28:36.320559", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-case"}
- {"dt": "2023-03-02T07:28:36.322143", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-event"}
- {"dt": "2023-03-02T07:28:36.324142", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-feedback"}
- {"dt": "2023-03-02T07:28:36.325675", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-raise"}
- {"dt": "2023-03-02T07:28:36.327287", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-reference"}
- {"dt": "2023-03-02T07:28:36.328845", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-runner"}
- {"dt": "2023-03-02T07:28:36.330431", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-set"}
- {"dt": "2023-03-02T07:28:36.332703", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-test-shell"}
- {"dt": "2023-03-02T07:28:36.334282", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-02T07:28:36.336541", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-02T07:28:36.338078", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/environment"}
- {"dt": "2023-03-02T07:28:36.339341", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-02T07:28:36.340206", "lvl": "debug", "msg": "- LAVA_JOB_ID=32063"}
- {"dt": "2023-03-02T07:28:36.340994", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-03-02T07:28:36.342230", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.345634", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:36.346720", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.348123", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-02T07:28:36.349132", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:36.350158", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.351408", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-02T07:28:36.352379", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:36.353332", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.354155", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-02T07:28:36.356278", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:56) [common]"}
- {"dt": "2023-03-02T07:28:36.357267", "lvl": "debug", "msg": "Using /lava-32063 at stage 0"}
- {"dt": "2023-03-02T07:28:36.358404", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:28:36.359337", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/0/tests/0_network-veth'"}
- {"dt": "2023-03-02T07:28:38.005784", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-03-02T07:28:38.009782", "lvl": "debug", "msg": "uuid=32063_1.5.4.1 testdef=None"}
- {"dt": "2023-03-02T07:28:38.010973", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:28:38.011744", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.65", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:38.013324", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:28:54) [common]"}
- {"dt": "2023-03-02T07:28:38.018729", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:38.019638", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32063_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:38.021269", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:28:54) [common]"}
- {"dt": "2023-03-02T07:28:38.028171", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:38.028948", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32063_1.5.4.1", "uuid": "32063_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:38.030620", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:28:54) [common]"}
- {"dt": "2023-03-02T07:28:38.036589", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/0/tests/0_network-veth test_uuid 32063_1.5.4.1"}
- {"dt": "2023-03-02T07:28:38.038150", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:38.038835", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/0/tests/0_network-veth/run.sh", "from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32063_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:38.040412", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:28:54) [common]"}
- {"dt": "2023-03-02T07:28:38.041037", "lvl": "debug", "msg": "Using /lava-32063 at stage 1"}
- {"dt": "2023-03-02T07:28:38.041940", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:28:38.042596", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-02T07:28:39.702508", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/1_ci-busybox"}
- {"dt": "2023-03-02T07:28:39.715255", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-02T07:28:39.719257", "lvl": "debug", "msg": "uuid=32063_1.5.4.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-02T07:28:39.720440", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:28:39.721161", "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.5.4.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:39.722634", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:28:53) [common]"}
- {"dt": "2023-03-02T07:28:39.728537", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:39.729232", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32063_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:39.730749", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:28:53) [common]"}
- {"dt": "2023-03-02T07:28:39.738819", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:39.739598", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32063_1.5.4.5", "uuid": "32063_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:39.741191", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:28:53) [common]"}
- {"dt": "2023-03-02T07:28:39.749451", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/1_ci-busybox test_uuid 32063_1.5.4.5"}
- {"dt": "2023-03-02T07:28:39.751157", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:39.751894", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/1_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": "32063_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:39.753493", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:28:53) [common]"}
- {"dt": "2023-03-02T07:28:39.754329", "lvl": "debug", "msg": "Using /lava-32063 at stage 1"}
- {"dt": "2023-03-02T07:28:39.755501", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:28:39.756267", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-02T07:28:41.405781", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T07:28:41.419602", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-02T07:28:41.422541", "lvl": "debug", "msg": "uuid=32063_1.5.4.9 testdef=None"}
- {"dt": "2023-03-02T07:28:41.423767", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:28:41.424532", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.67", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:41.426027", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:28:51) [common]"}
- {"dt": "2023-03-02T07:28:41.431234", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:41.431988", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "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": "32063_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:41.433618", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:28:51) [common]"}
- {"dt": "2023-03-02T07:28:41.441114", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:41.441837", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32063_1.5.4.9", "uuid": "32063_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:41.443505", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:28:51) [common]"}
- {"dt": "2023-03-02T07:28:41.450303", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/2_ci-smoke-tests-basic test_uuid 32063_1.5.4.9"}
- {"dt": "2023-03-02T07:28:41.453769", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:41.454494", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/2_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": "32063_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:41.456178", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:28:51) [common]"}
- {"dt": "2023-03-02T07:28:41.456921", "lvl": "debug", "msg": "Using /lava-32063 at stage 1"}
- {"dt": "2023-03-02T07:28:41.457933", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:28:41.458682", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-02T07:28:43.160466", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/3_ci-service-check"}
- {"dt": "2023-03-02T07:28:43.172203", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-02T07:28:43.176177", "lvl": "debug", "msg": "uuid=32063_1.5.4.13 testdef=None"}
- {"dt": "2023-03-02T07:28:43.177269", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:28:43.177949", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.72", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:43.179434", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:28:49) [common]"}
- {"dt": "2023-03-02T07:28:43.184941", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:43.185599", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32063_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:43.187013", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:28:49) [common]"}
- {"dt": "2023-03-02T07:28:43.194125", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:43.194820", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32063_1.5.4.13", "uuid": "32063_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:43.196396", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:28:49) [common]"}
- {"dt": "2023-03-02T07:28:43.203439", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/3_ci-service-check test_uuid 32063_1.5.4.13"}
- {"dt": "2023-03-02T07:28:43.204202", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-02T07:28:43.204673", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-02T07:28:43.205900", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:43.206557", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1/tests/3_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": "32063_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:43.208053", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:28:49) [common]"}
- {"dt": "2023-03-02T07:28:43.208665", "lvl": "debug", "msg": "Using /lava-32063 at stage 2"}
- {"dt": "2023-03-02T07:28:43.209528", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:28:43.210166", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-02T07:28:44.862157", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-02T07:28:44.880805", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-02T07:28:44.883960", "lvl": "debug", "msg": "uuid=32063_1.5.4.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-02T07:28:44.884998", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:28:44.885612", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.68", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:44.886981", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:44.892381", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:44.893053", "lvl": "results", "msg": {"case": "test-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", "uuid": "32063_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:44.894404", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:44.901768", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:44.902461", "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": "32063_1.5.4.17", "uuid": "32063_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:44.904488", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:44.911718", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/2/tests/4_ci-dumpjournal test_uuid 32063_1.5.4.17"}
- {"dt": "2023-03-02T07:28:44.913216", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:44.913888", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/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": "32063_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:44.915235", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-02T07:28:44.915748", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/0 for stage 0"}
- {"dt": "2023-03-02T07:28:44.916576", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-03-02T07:28:44.917336", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/1 for stage 1"}
- {"dt": "2023-03-02T07:28:44.918170", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-02T07:28:44.918757", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T07:28:44.919318", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-02T07:28:44.919927", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32063/lava-overlay-v8hjqd87/lava-32063/2 for stage 2"}
- {"dt": "2023-03-02T07:28:44.920741", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-02T07:28:44.921631", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:09) [common]"}
- {"dt": "2023-03-02T07:28:44.922417", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:45.444944", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:28:45.445816", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:45.446480", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.447346", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:09) [common]"}
- {"dt": "2023-03-02T07:28:45.448047", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:45.448696", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:45.449396", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.450010", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.450627", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:28:47) [common]"}
- {"dt": "2023-03-02T07:28:45.451232", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-03-02T07:28:45.451778", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61977, NBD-ROOT: 32063/nbd-deploy-dr8qv9zo/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:28:45.455090", "lvl": "debug", "msg": "nbd-server 61977 /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:28:45.482444", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-02T07:28:45.486602", "lvl": "debug", "msg": "output: ** (process:475): WARNING **: 07:28:45.480: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-03-02T07:28:45.487791", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-02T07:28:45.488667", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-03-02T07:28:45.490118", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.491568", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:13) [common]"}
- {"dt": "2023-03-02T07:28:45.492855", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:28:45.494007", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:28:45.495061", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.495894", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:28:45.497069", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-03-02T07:28:45.497683", "lvl": "debug", "msg": "- {BOOTX}: bootz 0x82000000 0x83000000:${initrd_size} 0x88000000"}
- {"dt": "2023-03-02T07:28:45.498184", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x88000000"}
- {"dt": "2023-03-02T07:28:45.498633", "lvl": "debug", "msg": "- {DTB}: 32063/nbd-deploy-dr8qv9zo/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:28:45.499140", "lvl": "debug", "msg": "- {INITRD}: 32063/nbd-deploy-dr8qv9zo/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:28:45.499598", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x82000000"}
- {"dt": "2023-03-02T07:28:45.500037", "lvl": "debug", "msg": "- {KERNEL}: 32063/nbd-deploy-dr8qv9zo/kernel/zImage"}
- {"dt": "2023-03-02T07:28:45.500491", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-03-02T07:28:45.500926", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-03-02T07:28:45.501357", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61977"}
- {"dt": "2023-03-02T07:28:45.501781", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-03-02T07:28:45.502226", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-03-02T07:28:45.502655", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x83000000"}
- {"dt": "2023-03-02T07:28:45.503140", "lvl": "debug", "msg": "- {RAMDISK}: 32063/nbd-deploy-dr8qv9zo/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:28:45.503612", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-03-02T07:28:45.504058", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-03-02T07:28:45.504492", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-03-02T07:28:45.504921", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-03-02T07:28:45.505347", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-03-02T07:28:45.505850", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-03-02T07:28:45.506288", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-03-02T07:28:45.506730", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-02T07:28:45.507255", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-02T07:28:45.507722", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-03-02T07:28:45.508161", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-03-02T07:28:45.508602", "lvl": "info", "msg": "- tftp 0x82000000 32063/nbd-deploy-dr8qv9zo/kernel/zImage"}
- {"dt": "2023-03-02T07:28:45.509035", "lvl": "info", "msg": "- tftp 0x83000000 32063/nbd-deploy-dr8qv9zo/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:28:45.509473", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-03-02T07:28:45.509906", "lvl": "info", "msg": "- tftp 0x88000000 32063/nbd-deploy-dr8qv9zo/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:28:45.510345", "lvl": "info", "msg": "- setenv bootargs 'console=ttyO0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61977 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-02T07:28:45.510828", "lvl": "info", "msg": "- bootz 0x82000000 0x83000000:${initrd_size} 0x88000000"}
- {"dt": "2023-03-02T07:28:45.511500", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:28:45.512060", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x88000000", "kernel_addr": "0x82000000", "ramdisk_addr": "0x83000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:28:45.513429", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:28:45.514020", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63005'"}
- {"dt": "2023-03-02T07:28:48.678278", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-03-02T07:28:48.684566", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:28:48.687047", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:28:48.689152", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:28:48.691118", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:28:48.692397", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '4'"}
- {"dt": "2023-03-02T07:28:51.846850", "lvl": "debug", "msg": ">> /usr/bin/nice: \u2018/usr/local/bin/acme-cli\u2019: No such file or directory\r"}
- {"dt": "2023-03-02T07:28:51.850809", "lvl": "debug", "msg": "Returned 127 in 3 seconds"}
- {"dt": "2023-03-02T07:28:51.851825", "lvl": "error", "msg": "Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'reset', '4']'"}
- {"dt": "2023-03-02T07:28:51.852958", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:28:51.853951", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:28:51.854998", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "3.16", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4.1.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:28:51.856883", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:28:51.857885", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:28:51.858828", "lvl": "results", "msg": {"case": "reset-device", "definition": "lava", "duration": "3.17", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:28:51.860221", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:28:51.860903", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:28:51.861629", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "3.17", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:28:51.862734", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed'"}
- {"dt": "2023-03-02T07:28:51.863551", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:28:51.864219", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:06) [common]"}
- {"dt": "2023-03-02T07:28:51.864899", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "6.37", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:28:51.866357", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-02T07:28:51.867227", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/initrd"}
- {"dt": "2023-03-02T07:28:51.869276", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/kernel"}
- {"dt": "2023-03-02T07:28:51.872246", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/dtb"}
- {"dt": "2023-03-02T07:28:51.873749", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32063/nbd-deploy-dr8qv9zo/nbdroot"}
- {"dt": "2023-03-02T07:28:52.380652", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-02T07:28:52.381884", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '4'"}
- {"dt": "2023-03-02T07:28:55.537484", "lvl": "debug", "msg": ">> /usr/bin/nice: \u2018/usr/local/bin/acme-cli\u2019: No such file or directory\r"}
- {"dt": "2023-03-02T07:28:55.543947", "lvl": "debug", "msg": "Returned 127 in 3 seconds"}
- {"dt": "2023-03-02T07:28:55.545866", "lvl": "error", "msg": "Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'switch_off', '4']'"}
- {"dt": "2023-03-02T07:28:55.547706", "lvl": "exception", "msg": "Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}
- {"dt": "2023-03-02T07:28:55.548368", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:28:55.549036", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.17", "extra": {"fail": "Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}, "level": "6.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:28:55.551600", "lvl": "error", "msg": "Failed to run 'finalize': Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}
- {"dt": "2023-03-02T07:28:55.556003", "lvl": "exception", "msg": "Traceback (most recent call last):\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/job.py\", line 252, in run\n    self._run()\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/job.py\", line 242, in _run\n    self.pipeline.run_actions(self.connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/logical.py\", line 68, in run\n    connection = self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 119, in run\n    self.run_cmd(cmd, error_msg=\"Unable to reboot: '%s' failed\" % cmd)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 695, in run_cmd\n    raise self.command_exception(error_msg)\nlava_common.exceptions.InfrastructureError: Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 351, in run\n    connection = super().run(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 244, in run\n    self.run_cmd(cmd, error_msg=\"Unable to power-off: '%s' failed\" % cmd)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 695, in run_cmd\n    raise self.command_exception(error_msg)\nlava_common.exceptions.InfrastructureError: Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed\n"}
- {"dt": "2023-03-02T07:28:55.657824", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-03-02T07:28:55.658974", "lvl": "debug", "msg": "clean NBD port 61977"}
- {"dt": "2023-03-02T07:28:58.908007", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32063"}
- {"dt": "2023-03-02T07:28:58.982470", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32063"}
- {"dt": "2023-03-02T07:28:58.983658", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-02T07:28:58.984350", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed", "error_type": "Infrastructure", "result": "fail"}}
