- {"dt": "2023-11-30T03:55:47.790726", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-11-30T03:55:47.792101", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-11-30T03:55:47.793233", "lvl": "info", "msg": "Start time: 2023-11-30 03:55:47.793165+00:00 (UTC)"}
- {"dt": "2023-11-30T03:55:47.794488", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-11-30T03:55:48.017491", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-11-30T03:55:48.230393", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-11-30T03:55:48.447380", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-11-30T03:55:48.672919", "lvl": "info", "msg": "validate duration: 0.88"}
- {"dt": "2023-11-30T03:55:48.673796", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-11-30T03:55:48.674972", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-11-30T03:55:48.675684", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-11-30T03:55:48.676398", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-11-30T03:55:48.677087", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-11-30T03:55:48.678579", "lvl": "debug", "msg": "Set_port 61992"}
- {"dt": "2023-11-30T03:55:48.679511", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-11-30T03:55:48.680407", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-11-30T03:55:48.681773", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:55:48.682560", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:55:48.683221", "lvl": "debug", "msg": "total size: 2029939 (1MB)"}
- {"dt": "2023-11-30T03:55:48.683824", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-30T03:55:48.991085", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-11-30T03:55:49.187321", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-11-30T03:55:49.284599", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-11-30T03:55:49.289436", "lvl": "debug", "msg": "progress  16% (0MB)"}
- {"dt": "2023-11-30T03:55:49.389275", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-11-30T03:55:49.398349", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-11-30T03:55:49.402708", "lvl": "debug", "msg": "progress  32% (0MB)"}
- {"dt": "2023-11-30T03:55:49.407036", "lvl": "debug", "msg": "progress  37% (0MB)"}
- {"dt": "2023-11-30T03:55:49.484161", "lvl": "debug", "msg": "progress  43% (0MB)"}
- {"dt": "2023-11-30T03:55:49.493292", "lvl": "debug", "msg": "progress  48% (0MB)"}
- {"dt": "2023-11-30T03:55:49.498012", "lvl": "debug", "msg": "progress  53% (1MB)"}
- {"dt": "2023-11-30T03:55:49.502556", "lvl": "debug", "msg": "progress  58% (1MB)"}
- {"dt": "2023-11-30T03:55:49.508284", "lvl": "debug", "msg": "progress  64% (1MB)"}
- {"dt": "2023-11-30T03:55:49.513014", "lvl": "debug", "msg": "progress  69% (1MB)"}
- {"dt": "2023-11-30T03:55:49.517988", "lvl": "debug", "msg": "progress  74% (1MB)"}
- {"dt": "2023-11-30T03:55:49.523004", "lvl": "debug", "msg": "progress  79% (1MB)"}
- {"dt": "2023-11-30T03:55:49.579299", "lvl": "debug", "msg": "progress  85% (1MB)"}
- {"dt": "2023-11-30T03:55:49.589057", "lvl": "debug", "msg": "progress  90% (1MB)"}
- {"dt": "2023-11-30T03:55:49.593503", "lvl": "debug", "msg": "progress  95% (1MB)"}
- {"dt": "2023-11-30T03:55:49.598235", "lvl": "debug", "msg": "progress 100% (1MB)"}
- {"dt": "2023-11-30T03:55:49.600261", "lvl": "info", "msg": "1MB downloaded in 0.92s (2.11MB/s)"}
- {"dt": "2023-11-30T03:55:49.601488", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:55:49.602176", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.92", "extra": {"label": "initrd", "md5sum": "9cd925eb14dc85be1ff88a4df7d97079", "sha256sum": "5afb321e720bfa40141d65b5f3e2b0762773e10f2daf3b79303971fa1a99542a", "sha512sum": "edef661a00c227c150052f5647211575dc290923c2925e1f6cc49b74531d46af7feac22c047fa95d582100bc837a540b37b20608230f9d2456ea7dba9c4664ce", "size": ! "2029939"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:55:49.603639", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:55:49.604474", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-11-30T03:55:49.605270", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-11-30T03:55:49.606354", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-11-30T03:55:49.607051", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/kernel/Image"}
- {"dt": "2023-11-30T03:55:49.607607", "lvl": "debug", "msg": "total size: 38128128 (36MB)"}
- {"dt": "2023-11-30T03:55:49.608151", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-30T03:55:49.912982", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-30T03:55:50.503016", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-11-30T03:55:50.706539", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-11-30T03:55:50.778761", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-11-30T03:55:50.854082", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-11-30T03:55:51.017198", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-11-30T03:55:51.088406", "lvl": "debug", "msg": "progress  30% (10MB)"}
- {"dt": "2023-11-30T03:55:51.313818", "lvl": "debug", "msg": "progress  35% (12MB)"}
- {"dt": "2023-11-30T03:55:51.385876", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-11-30T03:55:51.523065", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-11-30T03:55:51.698003", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-11-30T03:55:51.828892", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-11-30T03:55:52.006785", "lvl": "debug", "msg": "progress  60% (21MB)"}
- {"dt": "2023-11-30T03:55:52.149442", "lvl": "debug", "msg": "progress  65% (23MB)"}
- {"dt": "2023-11-30T03:55:52.335900", "lvl": "debug", "msg": "progress  70% (25MB)"}
- {"dt": "2023-11-30T03:55:52.633372", "lvl": "debug", "msg": "progress  75% (27MB)"}
- {"dt": "2023-11-30T03:55:52.883059", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-11-30T03:55:53.324784", "lvl": "debug", "msg": "progress  85% (30MB)"}
- {"dt": "2023-11-30T03:55:53.723033", "lvl": "debug", "msg": "progress  90% (32MB)"}
- {"dt": "2023-11-30T03:55:54.116574", "lvl": "debug", "msg": "progress  95% (34MB)"}
- {"dt": "2023-11-30T03:55:54.497177", "lvl": "debug", "msg": "progress 100% (36MB)"}
- {"dt": "2023-11-30T03:55:54.498791", "lvl": "info", "msg": "36MB downloaded in 4.89s (7.43MB/s)"}
- {"dt": "2023-11-30T03:55:54.499976", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:05) [common]"}
- {"dt": "2023-11-30T03:55:54.500757", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.89", "extra": {"label": "kernel", "md5sum": "ed7f732bc286fbf63929f3cc1e172006", "sha256sum": "efd62eac42a821d8b16454b7626547b6acab6881103a36f5e0c375ec38475fe6", "sha512sum": "65e00cda33ff687e0ac3e4c246546afbd36b0483d07e979e425934c132b2d6c792ee43f295c0003c40306f1d73b73a18da3868c30f67b1ead942f28856e9be22", "size": ! "38128128"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:55:54.502219", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:05) [common]"}
- {"dt": "2023-11-30T03:55:54.502954", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-11-30T03:55:54.503697", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-11-30T03:55:54.504766", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:55:54.505485", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:55:54.506118", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-11-30T03:55:54.506686", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-30T03:55:54.824111", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-11-30T03:55:54.927279", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-11-30T03:55:54.930150", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-11-30T03:55:54.931705", "lvl": "info", "msg": "0MB downloaded in 0.43s (0.19MB/s)"}
- {"dt": "2023-11-30T03:55:54.932943", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:55:54.933651", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.43", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:55:54.935199", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:55:54.936302", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-11-30T03:55:54.937190", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-11-30T03:55:54.938260", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29496/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-11-30T03:55:54.938974", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-30T03:55:54.939581", "lvl": "debug", "msg": "total size: 551524356 (525MB)"}
- {"dt": "2023-11-30T03:55:54.940118", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-11-30T03:55:55.272592", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-30T03:56:05.806173", "lvl": "debug", "msg": "progress   5% (26MB)"}
- {"dt": "2023-11-30T03:56:12.060837", "lvl": "debug", "msg": "progress  10% (52MB)"}
- {"dt": "2023-11-30T03:56:15.785601", "lvl": "debug", "msg": "progress  15% (78MB)"}
- {"dt": "2023-11-30T03:56:20.167912", "lvl": "debug", "msg": "progress  20% (105MB)"}
- {"dt": "2023-11-30T03:56:27.252820", "lvl": "debug", "msg": "progress  25% (131MB)"}
- {"dt": "2023-11-30T03:56:35.956482", "lvl": "debug", "msg": "progress  30% (157MB)"}
- {"dt": "2023-11-30T03:56:43.380785", "lvl": "debug", "msg": "progress  35% (184MB)"}
- {"dt": "2023-11-30T03:56:51.156788", "lvl": "debug", "msg": "progress  40% (210MB)"}
- {"dt": "2023-11-30T03:56:59.076964", "lvl": "debug", "msg": "progress  45% (236MB)"}
- {"dt": "2023-11-30T03:57:11.204995", "lvl": "debug", "msg": "progress  50% (263MB)"}
- {"dt": "2023-11-30T03:57:18.764749", "lvl": "debug", "msg": "progress  55% (289MB)"}
- {"dt": "2023-11-30T03:57:27.092793", "lvl": "debug", "msg": "progress  60% (315MB)"}
- {"dt": "2023-11-30T03:57:35.356770", "lvl": "debug", "msg": "progress  65% (341MB)"}
- {"dt": "2023-11-30T03:57:44.851349", "lvl": "debug", "msg": "progress  70% (368MB)"}
- {"dt": "2023-11-30T03:57:52.168782", "lvl": "debug", "msg": "progress  75% (394MB)"}
- {"dt": "2023-11-30T03:57:58.840808", "lvl": "debug", "msg": "progress  80% (420MB)"}
- {"dt": "2023-11-30T03:58:06.158525", "lvl": "debug", "msg": "progress  85% (447MB)"}
- {"dt": "2023-11-30T03:58:13.416649", "lvl": "debug", "msg": "progress  90% (473MB)"}
- {"dt": "2023-11-30T03:58:20.092780", "lvl": "debug", "msg": "progress  95% (499MB)"}
- {"dt": "2023-11-30T03:58:31.189227", "lvl": "debug", "msg": "progress 100% (525MB)"}
- {"dt": "2023-11-30T03:58:34.444399", "lvl": "info", "msg": "525MB downloaded in 159.50s (3.30MB/s)"}
- {"dt": "2023-11-30T03:58:34.446316", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:40) [common]"}
- {"dt": "2023-11-30T03:58:34.447100", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "159.51", "extra": {"label": "nbdroot", "md5sum": "650d90ad995356796e5b61d53808bc25", "sha256sum": "a74888d5748d3dbae7a02fbfc27801ce5f22fa90aef5845831fab2d7e4256e19", "sha512sum": "77fa8dc5a49c0b7ef21db72c7880856221ae5c55ec681a1340b66050a4988b91b8f898e01f7d04caa63e5eaf3121f29f73a6c4a002bacb0a07fdf34716184f9d", "size": ! "551524356"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:34.449309", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:40) [common]"}
- {"dt": "2023-11-30T03:58:34.450243", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.451846", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j"}
- {"dt": "2023-11-30T03:58:34.453596", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin"}
- {"dt": "2023-11-30T03:58:34.454714", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/tests"}
- {"dt": "2023-11-30T03:58:34.455735", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/results"}
- {"dt": "2023-11-30T03:58:34.457298", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-add-keys"}
- {"dt": "2023-11-30T03:58:34.458733", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-add-sources"}
- {"dt": "2023-11-30T03:58:34.460080", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-background-process-start"}
- {"dt": "2023-11-30T03:58:34.462052", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-background-process-stop"}
- {"dt": "2023-11-30T03:58:34.463415", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-common-functions"}
- {"dt": "2023-11-30T03:58:34.465317", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-echo-ipv4"}
- {"dt": "2023-11-30T03:58:34.466690", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-install-packages"}
- {"dt": "2023-11-30T03:58:34.468004", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-installed-packages"}
- {"dt": "2023-11-30T03:58:34.470279", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-os-build"}
- {"dt": "2023-11-30T03:58:34.471883", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-probe-channel"}
- {"dt": "2023-11-30T03:58:34.473340", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-probe-ip"}
- {"dt": "2023-11-30T03:58:34.474682", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-target-ip"}
- {"dt": "2023-11-30T03:58:34.475976", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-target-mac"}
- {"dt": "2023-11-30T03:58:34.477938", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-target-storage"}
- {"dt": "2023-11-30T03:58:34.479309", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-case"}
- {"dt": "2023-11-30T03:58:34.480722", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-event"}
- {"dt": "2023-11-30T03:58:34.482147", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-feedback"}
- {"dt": "2023-11-30T03:58:34.483493", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-raise"}
- {"dt": "2023-11-30T03:58:34.484961", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-reference"}
- {"dt": "2023-11-30T03:58:34.486336", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-runner"}
- {"dt": "2023-11-30T03:58:34.487695", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-set"}
- {"dt": "2023-11-30T03:58:34.489123", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-test-shell"}
- {"dt": "2023-11-30T03:58:34.490453", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-install-packages (oe)"}
- {"dt": "2023-11-30T03:58:34.491964", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/bin/lava-installed-packages (oe)"}
- {"dt": "2023-11-30T03:58:34.494022", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/environment"}
- {"dt": "2023-11-30T03:58:34.495172", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-11-30T03:58:34.495918", "lvl": "debug", "msg": "- LAVA_JOB_ID=33923"}
- {"dt": "2023-11-30T03:58:34.497186", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-11-30T03:58:34.498263", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.501164", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:34.502100", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.502814", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-11-30T03:58:34.503532", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:34.504955", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.505729", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-11-30T03:58:34.506530", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:34.507355", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.508107", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-11-30T03:58:34.509749", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:34.510586", "lvl": "debug", "msg": "Using /lava-33923 at stage 0"}
- {"dt": "2023-11-30T03:58:34.511607", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:34.512826", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/0/tests/0_network-veth'"}
- {"dt": "2023-11-30T03:58:35.142909", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-11-30T03:58:35.146927", "lvl": "debug", "msg": "uuid=33923_1.5.4.1 testdef=None"}
- {"dt": "2023-11-30T03:58:35.148118", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:35.149359", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.64", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-11-30T03:58:35.151055", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:35.156435", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.157226", "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": "33923_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.158961", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:35.165348", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.166055", "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": "33923_1.5.4.1", "uuid": "33923_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.167826", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:14) [common]"}
- {"dt": "2023-11-30T03:58:35.173849", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/0/tests/0_network-veth test_uuid 33923_1.5.4.1"}
- {"dt": "2023-11-30T03:58:35.175542", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.176295", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/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": "33923_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.177910", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.178665", "lvl": "debug", "msg": "Using /lava-33923 at stage 1"}
- {"dt": "2023-11-30T03:58:35.181769", "lvl": "debug", "msg": "uuid=33923_1.5.4.5 testdef=None"}
- {"dt": "2023-11-30T03:58:35.182594", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.183437", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.188284", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.188983", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "HACK-SPEC-3655", "path": "inline/hack-spec-3655.yaml", "uuid": "33923_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.190469", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.197555", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.198382", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "inline", "name": "HACK-SPEC-3655", "path": "inline/hack-spec-3655.yaml", "skipped test-install-overlay": "33923_1.5.4.5", "uuid": "33923_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.200088", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.207093", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/1/tests/1_HACK-SPEC-3655 test_uuid 33923_1.5.4.5"}
- {"dt": "2023-11-30T03:58:35.208930", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.209723", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/1/tests/1_HACK-SPEC-3655/run.sh", "from": "inline", "name": "HACK-SPEC-3655", "path": "inline/hack-spec-3655.yaml", "uuid": "33923_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.211466", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.212349", "lvl": "debug", "msg": "Using /lava-33923 at stage 2"}
- {"dt": "2023-11-30T03:58:35.213511", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:35.214359", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/2_ci-busybox'"}
- {"dt": "2023-11-30T03:58:35.841286", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/2_ci-busybox"}
- {"dt": "2023-11-30T03:58:35.857863", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/2_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-11-30T03:58:35.861573", "lvl": "debug", "msg": "uuid=33923_1.5.4.9 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-11-30T03:58:35.862717", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:35.863454", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.865039", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.870850", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.871538", "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": "33923_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.873098", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.882653", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.883380", "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": "33923_1.5.4.9", "uuid": "33923_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.885019", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.892789", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/2_ci-busybox test_uuid 33923_1.5.4.9"}
- {"dt": "2023-11-30T03:58:35.894458", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:35.895175", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/2_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": "33923_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:35.896812", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:13) [common]"}
- {"dt": "2023-11-30T03:58:35.897561", "lvl": "debug", "msg": "Using /lava-33923 at stage 2"}
- {"dt": "2023-11-30T03:58:35.898595", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:35.899366", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/3_ci-smoke-tests-basic'"}
- {"dt": "2023-11-30T03:58:36.539169", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/3_ci-smoke-tests-basic"}
- {"dt": "2023-11-30T03:58:36.555548", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/3_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-11-30T03:58:36.558645", "lvl": "debug", "msg": "uuid=33923_1.5.4.13 testdef=None"}
- {"dt": "2023-11-30T03:58:36.559674", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:36.560345", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:36.561759", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:12) [common]"}
- {"dt": "2023-11-30T03:58:36.566847", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:36.567518", "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": "33923_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:36.568976", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:12) [common]"}
- {"dt": "2023-11-30T03:58:36.575687", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:36.576444", "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": "33923_1.5.4.13", "uuid": "33923_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:36.577920", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:12) [common]"}
- {"dt": "2023-11-30T03:58:36.584963", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/3_ci-smoke-tests-basic test_uuid 33923_1.5.4.13"}
- {"dt": "2023-11-30T03:58:36.587701", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:36.588501", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/3_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": "33923_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:36.590063", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:12) [common]"}
- {"dt": "2023-11-30T03:58:36.590724", "lvl": "debug", "msg": "Using /lava-33923 at stage 2"}
- {"dt": "2023-11-30T03:58:36.591643", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:36.592363", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/4_ci-service-check'"}
- {"dt": "2023-11-30T03:58:37.221530", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/4_ci-service-check"}
- {"dt": "2023-11-30T03:58:37.239317", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/4_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-11-30T03:58:37.242470", "lvl": "debug", "msg": "uuid=33923_1.5.4.17 testdef=None"}
- {"dt": "2023-11-30T03:58:37.243530", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:37.244293", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:37.245857", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:11) [common]"}
- {"dt": "2023-11-30T03:58:37.251269", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:37.251945", "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": "33923_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:37.253505", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:11) [common]"}
- {"dt": "2023-11-30T03:58:37.261224", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:37.261979", "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": "33923_1.5.4.17", "uuid": "33923_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:37.263596", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:11) [common]"}
- {"dt": "2023-11-30T03:58:37.270630", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/4_ci-service-check test_uuid 33923_1.5.4.17"}
- {"dt": "2023-11-30T03:58:37.271398", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-11-30T03:58:37.271991", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-11-30T03:58:37.273571", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:37.274322", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2/tests/4_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": "33923_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:37.275905", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:11) [common]"}
- {"dt": "2023-11-30T03:58:37.276691", "lvl": "debug", "msg": "Using /lava-33923 at stage 3"}
- {"dt": "2023-11-30T03:58:37.277742", "lvl": "info", "msg": "Fetching tests from https://git.linaro.org/qa/test-definitions.git"}
- {"dt": "2023-11-30T03:58:37.278507", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.linaro.org/qa/test-definitions.git /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3/tests/5_ci-linaro-ptest'"}
- {"dt": "2023-11-30T03:58:41.992479", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3/tests/5_ci-linaro-ptest"}
- {"dt": "2023-11-30T03:58:42.009412", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3/tests/5_ci-linaro-ptest/automated/linux/ptest/ptest.yaml"}
- {"dt": "2023-11-30T03:58:42.012749", "lvl": "debug", "msg": "uuid=33923_1.5.4.21 testdef=None"}
- {"dt": "2023-11-30T03:58:42.013759", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:05) [common]"}
- {"dt": "2023-11-30T03:58:42.014382", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "4.74", "extra": {"commit": "c5c3faacdd3ecff31c6ac02c1ebd3c607b66d419", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.015709", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:07) [common]"}
- {"dt": "2023-11-30T03:58:42.021895", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.022555", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "uuid": "33923_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.024152", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:07) [common]"}
- {"dt": "2023-11-30T03:58:42.032137", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.032881", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "33923_1.5.4.21", "uuid": "33923_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.034379", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:07) [common]"}
- {"dt": "2023-11-30T03:58:42.041926", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3/tests/5_ci-linaro-ptest test_uuid 33923_1.5.4.21"}
- {"dt": "2023-11-30T03:58:42.042656", "lvl": "debug", "msg": "TESTS='xmlsec1 libxml2 lua'"}
- {"dt": "2023-11-30T03:58:42.044003", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.044771", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3/tests/5_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": "33923_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.046195", "lvl": "debug", "msg": "start: 1.5.4.25 git-repo-action (timeout 01:27:07) [common]"}
- {"dt": "2023-11-30T03:58:42.046821", "lvl": "debug", "msg": "Using /lava-33923 at stage 4"}
- {"dt": "2023-11-30T03:58:42.047709", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:42.048438", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/4/tests/6_pyagl-tests'"}
- {"dt": "2023-11-30T03:58:42.772385", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/4/tests/6_pyagl-tests/test-suites/short-smoke/pyagl-nohw-oneshot.yaml"}
- {"dt": "2023-11-30T03:58:42.777008", "lvl": "debug", "msg": "uuid=33923_1.5.4.25 testdef=None"}
- {"dt": "2023-11-30T03:58:42.778692", "lvl": "debug", "msg": "end: 1.5.4.25 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:42.779450", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.73", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.25", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.781275", "lvl": "debug", "msg": "start: 1.5.4.26 test-overlay (timeout 01:27:06) [common]"}
- {"dt": "2023-11-30T03:58:42.787395", "lvl": "debug", "msg": "end: 1.5.4.26 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.788277", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33923_1.5.4.25"}, "level": "1.5.4.26", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.790031", "lvl": "debug", "msg": "start: 1.5.4.27 test-install-overlay (timeout 01:27:06) [common]"}
- {"dt": "2023-11-30T03:58:42.802137", "lvl": "debug", "msg": "end: 1.5.4.27 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.802925", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "33923_1.5.4.25", "uuid": "33923_1.5.4.25"}, "level": "1.5.4.27", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.804697", "lvl": "debug", "msg": "start: 1.5.4.28 test-runscript-overlay (timeout 01:27:06) [common]"}
- {"dt": "2023-11-30T03:58:42.812888", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/4/tests/6_pyagl-tests test_uuid 33923_1.5.4.25"}
- {"dt": "2023-11-30T03:58:42.814547", "lvl": "debug", "msg": "end: 1.5.4.28 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:42.815264", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/4/tests/6_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": "33923_1.5.4.25"}, "level": "1.5.4.28", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:42.816981", "lvl": "debug", "msg": "start: 1.5.4.29 git-repo-action (timeout 01:27:06) [common]"}
- {"dt": "2023-11-30T03:58:42.817735", "lvl": "debug", "msg": "Using /lava-33923 at stage 5"}
- {"dt": "2023-11-30T03:58:42.818764", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:42.819531", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/5/tests/7_can_loopback'"}
- {"dt": "2023-11-30T03:58:43.471337", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/5/tests/7_can_loopback/test-suites/short-smoke/test_can_loopback.yaml"}
- {"dt": "2023-11-30T03:58:43.475131", "lvl": "debug", "msg": "uuid=33923_1.5.4.29 testdef=None"}
- {"dt": "2023-11-30T03:58:43.476594", "lvl": "debug", "msg": "end: 1.5.4.29 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:43.477505", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.66", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/test_can_loopback.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.29", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:43.479296", "lvl": "debug", "msg": "start: 1.5.4.30 test-overlay (timeout 01:27:05) [common]"}
- {"dt": "2023-11-30T03:58:43.484610", "lvl": "debug", "msg": "end: 1.5.4.30 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:43.485338", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "can_loopback", "path": "test-suites/short-smoke/test_can_loopback.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33923_1.5.4.29"}, "level": "1.5.4.30", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:43.486808", "lvl": "debug", "msg": "start: 1.5.4.31 test-install-overlay (timeout 01:27:05) [common]"}
- {"dt": "2023-11-30T03:58:43.493679", "lvl": "debug", "msg": "end: 1.5.4.31 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:43.494495", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "can_loopback", "path": "test-suites/short-smoke/test_can_loopback.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33923_1.5.4.29", "uuid": "33923_1.5.4.29"}, "level": "1.5.4.31", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:43.496326", "lvl": "debug", "msg": "start: 1.5.4.32 test-runscript-overlay (timeout 01:27:05) [common]"}
- {"dt": "2023-11-30T03:58:43.503049", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/5/tests/7_can_loopback test_uuid 33923_1.5.4.29"}
- {"dt": "2023-11-30T03:58:43.504733", "lvl": "debug", "msg": "end: 1.5.4.32 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:43.505507", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/5/tests/7_can_loopback/run.sh", "from": "git", "name": "can_loopback", "path": "test-suites/short-smoke/test_can_loopback.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33923_1.5.4.29"}, "level": "1.5.4.32", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:43.507262", "lvl": "debug", "msg": "start: 1.5.4.33 git-repo-action (timeout 01:27:05) [common]"}
- {"dt": "2023-11-30T03:58:43.508100", "lvl": "debug", "msg": "Using /lava-33923 at stage 6"}
- {"dt": "2023-11-30T03:58:43.509275", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:43.510128", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/6/tests/8_screenshooter'"}
- {"dt": "2023-11-30T03:58:44.201750", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/6/tests/8_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-11-30T03:58:44.205668", "lvl": "debug", "msg": "uuid=33923_1.5.4.33 testdef=None"}
- {"dt": "2023-11-30T03:58:44.207100", "lvl": "debug", "msg": "end: 1.5.4.33 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:44.207974", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.70", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.33", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.210017", "lvl": "debug", "msg": "start: 1.5.4.34 test-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.216365", "lvl": "debug", "msg": "end: 1.5.4.34 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.217157", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33923_1.5.4.33"}, "level": "1.5.4.34", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.218906", "lvl": "debug", "msg": "start: 1.5.4.35 test-install-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.225652", "lvl": "debug", "msg": "end: 1.5.4.35 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.226335", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33923_1.5.4.33", "uuid": "33923_1.5.4.33"}, "level": "1.5.4.35", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.227788", "lvl": "debug", "msg": "start: 1.5.4.36 test-runscript-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.233466", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/6/tests/8_screenshooter test_uuid 33923_1.5.4.33"}
- {"dt": "2023-11-30T03:58:44.235014", "lvl": "debug", "msg": "end: 1.5.4.36 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.235690", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/6/tests/8_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33923_1.5.4.33"}, "level": "1.5.4.36", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.237211", "lvl": "debug", "msg": "start: 1.5.4.37 git-repo-action (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.237845", "lvl": "debug", "msg": "Using /lava-33923 at stage 7"}
- {"dt": "2023-11-30T03:58:44.238751", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-30T03:58:44.239420", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7/tests/9_ci-dumpjournal'"}
- {"dt": "2023-11-30T03:58:44.902073", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7/tests/9_ci-dumpjournal"}
- {"dt": "2023-11-30T03:58:44.918423", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7/tests/9_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-11-30T03:58:44.921631", "lvl": "debug", "msg": "uuid=33923_1.5.4.37 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-11-30T03:58:44.922700", "lvl": "debug", "msg": "end: 1.5.4.37 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T03:58:44.923351", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.69", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.37", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.924773", "lvl": "debug", "msg": "start: 1.5.4.38 test-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.930251", "lvl": "debug", "msg": "end: 1.5.4.38 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.930922", "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": "33923_1.5.4.37"}, "level": "1.5.4.38", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.932901", "lvl": "debug", "msg": "start: 1.5.4.39 test-install-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.940575", "lvl": "debug", "msg": "end: 1.5.4.39 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.941299", "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": "33923_1.5.4.37", "uuid": "33923_1.5.4.37"}, "level": "1.5.4.39", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.942814", "lvl": "debug", "msg": "start: 1.5.4.40 test-runscript-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:58:44.949939", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7/tests/9_ci-dumpjournal test_uuid 33923_1.5.4.37"}
- {"dt": "2023-11-30T03:58:44.951526", "lvl": "debug", "msg": "end: 1.5.4.40 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:58:44.952285", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7/tests/9_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": "33923_1.5.4.37"}, "level": "1.5.4.40", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:58:44.953637", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-11-30T03:58:44.954142", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/0 for stage 0"}
- {"dt": "2023-11-30T03:58:44.954998", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-11-30T03:58:44.955901", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/1 for stage 1"}
- {"dt": "2023-11-30T03:58:44.956908", "lvl": "debug", "msg": "- 1_HACK-SPEC-3655"}
- {"dt": "2023-11-30T03:58:44.957691", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/2 for stage 2"}
- {"dt": "2023-11-30T03:58:44.958548", "lvl": "debug", "msg": "- 2_ci-busybox"}
- {"dt": "2023-11-30T03:58:44.959135", "lvl": "debug", "msg": "- 3_ci-smoke-tests-basic"}
- {"dt": "2023-11-30T03:58:44.959612", "lvl": "debug", "msg": "- 4_ci-service-check"}
- {"dt": "2023-11-30T03:58:44.960282", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/3 for stage 3"}
- {"dt": "2023-11-30T03:58:44.961184", "lvl": "debug", "msg": "- 5_ci-linaro-ptest"}
- {"dt": "2023-11-30T03:58:44.961913", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/4 for stage 4"}
- {"dt": "2023-11-30T03:58:44.962755", "lvl": "debug", "msg": "- 6_pyagl-tests"}
- {"dt": "2023-11-30T03:58:44.963466", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/5 for stage 5"}
- {"dt": "2023-11-30T03:58:44.964368", "lvl": "debug", "msg": "- 7_can_loopback"}
- {"dt": "2023-11-30T03:58:44.965120", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/6 for stage 6"}
- {"dt": "2023-11-30T03:58:44.965983", "lvl": "debug", "msg": "- 8_screenshooter"}
- {"dt": "2023-11-30T03:58:44.966689", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33923/lava-overlay-uwz6ru9j/lava-33923/7 for stage 7"}
- {"dt": "2023-11-30T03:58:44.967525", "lvl": "debug", "msg": "- 9_ci-dumpjournal"}
- {"dt": "2023-11-30T03:58:44.968499", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:10) [common]"}
- {"dt": "2023-11-30T03:58:44.969346", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:04) [common]"}
- {"dt": "2023-11-30T03:59:13.345831", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:28) [common]"}
- {"dt": "2023-11-30T03:59:13.346783", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:26:35) [common]"}
- {"dt": "2023-11-30T03:59:13.347472", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.348260", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:39) [common]"}
- {"dt": "2023-11-30T03:59:13.349005", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:26:35) [common]"}
- {"dt": "2023-11-30T03:59:13.349713", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:26:35) [common]"}
- {"dt": "2023-11-30T03:59:13.350430", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.351065", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.351786", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:26:35) [common]"}
- {"dt": "2023-11-30T03:59:13.352408", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-11-30T03:59:13.352972", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61992, NBD-ROOT: 33923/nbd-deploy-t8za3u8n/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-30T03:59:13.356421", "lvl": "debug", "msg": "nbd-server 61992 /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-30T03:59:13.374264", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-30T03:59:13.376395", "lvl": "debug", "msg": "output: ** (process:16460): WARNING **: 03:59:13.372: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-11-30T03:59:13.377045", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-30T03:59:13.377597", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-11-30T03:59:13.378583", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.379430", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:03:25) [common]"}
- {"dt": "2023-11-30T03:59:13.380366", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-11-30T03:59:13.381319", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-11-30T03:59:13.382040", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.382808", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-11-30T03:59:13.384105", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-11-30T03:59:13.384862", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-30T03:59:13.385426", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-11-30T03:59:13.385938", "lvl": "debug", "msg": "- {DTB}: 33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:59:13.386442", "lvl": "debug", "msg": "- {INITRD}: 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:59:13.386941", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-11-30T03:59:13.387418", "lvl": "debug", "msg": "- {KERNEL}: 33923/nbd-deploy-t8za3u8n/kernel/Image"}
- {"dt": "2023-11-30T03:59:13.387972", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-11-30T03:59:13.388590", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-11-30T03:59:13.389108", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61992"}
- {"dt": "2023-11-30T03:59:13.389742", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-11-30T03:59:13.390281", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-11-30T03:59:13.390786", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-11-30T03:59:13.391279", "lvl": "debug", "msg": "- {RAMDISK}: 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:59:13.391775", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-11-30T03:59:13.392390", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-11-30T03:59:13.392926", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-11-30T03:59:13.393408", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-11-30T03:59:13.393905", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-11-30T03:59:13.394383", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-11-30T03:59:13.394837", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-11-30T03:59:13.395286", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:13.395737", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:13.396344", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-11-30T03:59:13.396867", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-11-30T03:59:13.397343", "lvl": "info", "msg": "- tftp 0x68080000 33923/nbd-deploy-t8za3u8n/kernel/Image"}
- {"dt": "2023-11-30T03:59:13.397811", "lvl": "info", "msg": "- tftp 0x4a000000 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:59:13.398308", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-11-30T03:59:13.398783", "lvl": "info", "msg": "- tftp 0x68000000 33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:59:13.399251", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7   ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-11-30T03:59:13.399870", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-30T03:59:13.400623", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-30T03:59:13.401267", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:59:13.402802", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-11-30T03:59:13.403511", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-11-30T03:59:17.401464", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-11-30T03:59:17.403739", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:04) [common]"}
- {"dt": "2023-11-30T03:59:17.404679", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:56) [common]"}
- {"dt": "2023-11-30T03:59:17.405433", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:56) [common]"}
- {"dt": "2023-11-30T03:59:17.406123", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:56) [common]"}
- {"dt": "2023-11-30T03:59:17.407274", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-11-30T03:59:21.484039", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-30T03:59:22.557673", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-30T03:59:22.595864", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-11-30T03:59:22.700647", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-11-30T03:59:22.702963", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.29", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T03:59:22.707338", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-11-30T03:59:22.709130", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:51) [common]"}
- {"dt": "2023-11-30T03:59:22.709883", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-11-30T03:59:22.710539", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-11-30T03:59:22.712272", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-11-30T03:59:22.712982", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-11-30T03:59:22.713597", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-11-30T03:59:22.770109", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initia\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:22.771136", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:22.834643", "lvl": "target", "msg": "NOTICE:  BL2: B\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:22.836415", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:22.919262", "lvl": "target", "msg": "NOTICE:\0NOTICE:  BL2: R-Car Gen3 Initial Prog\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:22.920089", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:22.966431", "lvl": "target", "msg": "NOTIC\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:22.967579", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.010832", "lvl": "target", "msg": "NOTICE:  BL2: Boa\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.012431", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.059192", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier R\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.061068", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.063035", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-30T03:59:23.109695", "lvl": "target", "msg": "NOTICE:  BL2:\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.111729", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.113646", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-30T03:59:23.163547", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFl\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.165447", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.167069", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-30T03:59:23.169098", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-30T03:59:23.223341", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.225489", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.228286", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-30T03:59:23.230167", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-30T03:59:23.231950", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-30T03:59:23.233105", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-30T03:59:23.291979", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-30T03:59:23.292842", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-30T03:59:23.293440", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-30T03:59:23.294465", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-30T03:59:23.295514", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-30T03:59:23.296550", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-30T03:59:23.297534", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-11-30T03:59:23.298642", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-11-30T03:59:23.336788", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-11-30T03:59:23.338057", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-11-30T03:59:23.339871", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-11-30T03:59:23.341400", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-11-30T03:59:23.342761", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.344101", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.345663", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.347215", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.348840", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-11-30T03:59:23.350392", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-11-30T03:59:23.352091", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-11-30T03:59:23.379727", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-11-30T03:59:23.381317", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-11-30T03:59:23.382414", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-11-30T03:59:23.383399", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-11-30T03:59:23.384464", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-11-30T03:59:23.385482", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-11-30T03:59:23.386665", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-11-30T03:59:23.419483", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-11-30T03:59:23.420979", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-11-30T03:59:23.423326", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-11-30T03:59:23.752404", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:23.754216", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:23.755849", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-11-30T03:59:23.757569", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:23.759312", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-11-30T03:59:23.761878", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-11-30T03:59:23.945865", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-11-30T03:59:23.970529", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-11-30T03:59:23.972149", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-11-30T03:59:23.974383", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.975959", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.977565", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-11-30T03:59:23.979094", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:24.023295", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-11-30T03:59:24.189865", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-11-30T03:59:24.191668", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-11-30T03:59:24.193434", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-11-30T03:59:24.195909", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-11-30T03:59:24.240493", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-11-30T03:59:24.243215", "lvl": "input", "msg": "\n"}
- {"dt": "2023-11-30T03:59:24.295695", "lvl": "target", "msg": "Hit any key to stop autoboot:"}
- {"dt": "2023-11-30T03:59:24.298349", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-11-30T03:59:24.300776", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:49) [common]"}
- {"dt": "2023-11-30T03:59:24.302871", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-11-30T03:59:24.304984", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:49)"}
- {"dt": "2023-11-30T03:59:24.320547", "lvl": "target", "msg": "  3 \b\b\b 0 "}
- {"dt": "2023-11-30T03:59:24.322360", "lvl": "debug", "msg": "Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-11-30T03:59:24.323767", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-11-30T03:59:24.426065", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-11-30T03:59:24.428301", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-30T03:59:24.448352", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-11-30T03:59:24.449605", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-11-30T03:59:24.551383", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:24.553366", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-30T03:59:24.560029", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:24.562422", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-11-30T03:59:24.663989", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:24.665995", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-30T03:59:24.672143", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-30T03:59:24.674405", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-11-30T03:59:24.776043", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-11-30T03:59:24.778230", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-30T03:59:24.783963", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-11-30T03:59:27.981117", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2023-11-30T03:59:27.983113", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-11-30T03:59:28.237337", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-11-30T03:59:28.732743", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-11-30T03:59:29.019942", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1027 ms)"}
- {"dt": "2023-11-30T03:59:29.022421", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-11-30T03:59:29.125279", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-11-30T03:59:29.127436", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:44)"}
- {"dt": "2023-11-30T03:59:29.132093", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-11-30T03:59:29.134637", "lvl": "input", "msg": "tftp 0x68080000 33923/nbd-deploy-t8za3u8n/kernel/Image\n"}
- {"dt": "2023-11-30T03:59:29.237594", "lvl": "target", "msg": "=> tftp 0x68080000 33923/nbd-deploy-t8za3u8n/kernel/Image"}
- {"dt": "2023-11-30T03:59:29.239731", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:44)"}
- {"dt": "2023-11-30T03:59:29.243930", "lvl": "target", "msg": "tftp 0x68080000 33923/nbd-deploy-t8za3u8n/kernel/Image"}
- {"dt": "2023-11-30T03:59:29.270699", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-30T03:59:29.271809", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-30T03:59:29.274873", "lvl": "target", "msg": "Filename '33923/nbd-deploy-t8za3u8n/kernel/Image'."}
- {"dt": "2023-11-30T03:59:29.275503", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-11-30T03:59:29.864831", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-30T03:59:30.439850", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:31.015662", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:31.591202", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:32.166628", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:32.758330", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:33.349728", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:33.909420", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:34.484787", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:35.060171", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:35.635842", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:36.211301", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:36.786814", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:37.362259", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:37.953826", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:38.529308", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:39.152952", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:40.223463", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:40.862161", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:41.421680", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:41.981387", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:42.556676", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:43.371823", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:44.011896", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:44.619021", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:45.322098", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:46.984964", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:48.710310", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:50.133520", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:51.171654", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:51.778835", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:52.370033", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:52.929749", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:53.505563", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:54.096910", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:54.704160", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:55.359474", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:55.934890", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:56.510812", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:57.070411", "lvl": "target", "msg": "\t ###############################################################"}
- {"dt": "2023-11-30T03:59:57.071820", "lvl": "target", "msg": "\t 1.3 MiB/s"}
- {"dt": "2023-11-30T03:59:57.073250", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-30T03:59:57.086748", "lvl": "target", "msg": "Bytes transferred = 38128128 (245ca00 hex)"}
- {"dt": "2023-11-30T03:59:57.088776", "lvl": "input", "msg": "tftp 0x4a000000 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-11-30T03:59:57.190923", "lvl": "target", "msg": "=> tftp 0x4a000000 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:59:57.192141", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:16)"}
- {"dt": "2023-11-30T03:59:57.197489", "lvl": "target", "msg": "tftp 0x4a000000 33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-30T03:59:57.228672", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-30T03:59:57.229768", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-30T03:59:57.230917", "lvl": "target", "msg": "Filename '33923/nbd-deploy-t8za3u8n/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-11-30T03:59:57.232479", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-11-30T03:59:57.806888", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-30T03:59:58.446255", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-30T03:59:58.510439", "lvl": "target", "msg": "\t #########"}
- {"dt": "2023-11-30T03:59:58.511869", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-11-30T03:59:58.513124", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-30T03:59:58.526414", "lvl": "target", "msg": "Bytes transferred = 2029939 (1ef973 hex)"}
- {"dt": "2023-11-30T03:59:58.528665", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-11-30T03:59:58.631205", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-11-30T03:59:58.633632", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:15)"}
- {"dt": "2023-11-30T03:59:58.638693", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-11-30T03:59:58.640959", "lvl": "input", "msg": "tftp 0x68000000 33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-11-30T03:59:58.743845", "lvl": "target", "msg": "=> tftp 0x68000000 33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:59:58.745547", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:15)"}
- {"dt": "2023-11-30T03:59:58.749536", "lvl": "target", "msg": "tftp 0x68000000 33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-30T03:59:58.782110", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-30T03:59:58.783705", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-30T03:59:58.784921", "lvl": "target", "msg": "Filename '33923/nbd-deploy-t8za3u8n/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-11-30T03:59:58.785980", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-11-30T03:59:58.857575", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-11-30T03:59:58.859196", "lvl": "target", "msg": "\t 1.2 MiB/s"}
- {"dt": "2023-11-30T03:59:58.861204", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-30T03:59:58.862489", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-11-30T03:59:58.864164", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7   ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-11-30T03:59:58.966323", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7   ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-11-30T03:59:58.967685", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:14)"}
- {"dt": "2023-11-30T03:59:58.985320", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7   ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-11-30T03:59:58.986565", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-11-30T03:59:59.088343", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-30T03:59:59.089653", "lvl": "debug", "msg": "Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-11-30T03:59:59.090827", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:14)"}
- {"dt": "2023-11-30T03:59:59.096648", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-30T03:59:59.112380", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a6f0000"}
- {"dt": "2023-11-30T03:59:59.252270", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-11-30T03:59:59.254118", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-11-30T03:59:59.255303", "lvl": "target", "msg": "   Loading Ramdisk to 7fe10000, end 7ffff973 ... OK"}
- {"dt": "2023-11-30T03:59:59.256470", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-11-30T03:59:59.257527", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-11-30T03:59:59.258504", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-11-30T03:59:59.259470", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-11-30T03:59:59.260505", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-11-30T03:59:59.261690", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-11-30T03:59:59.297053", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-11-30T03:59:59.298099", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-11-30T03:59:59.299057", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-11-30T03:59:59.300521", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-11-30T03:59:59.301434", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-11-30T03:59:59.302275", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-11-30T03:59:59.303108", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-11-30T03:59:59.303944", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-11-30T03:59:59.304869", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-11-30T03:59:59.305715", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-11-30T03:59:59.306730", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-11-30T03:59:59.317763", "lvl": "target", "msg": "x28: 000000007bf04550 x29: 000000007beeed10"}
- {"dt": "2023-11-30T03:59:59.318397", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:59.320541", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-11-30T03:59:59.321556", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-11-30T03:59:59.322460", "lvl": "target", "msg": ""}
- {"dt": "2023-11-30T03:59:59.323906", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-30T03:59:59.325121", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:35) [common]"}
- {"dt": "2023-11-30T03:59:59.326149", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "35.02", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-30T03:59:59.327933", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-30T03:59:59.328773", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:42) [common]"}
- {"dt": "2023-11-30T03:59:59.329618", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "41.92", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-30T03:59:59.331049", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-11-30T03:59:59.332146", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-30T03:59:59.332934", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:46) [common]"}
- {"dt": "2023-11-30T03:59:59.333783", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "45.95", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-30T03:59:59.335275", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-11-30T03:59:59.336175", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/initrd"}
- {"dt": "2023-11-30T03:59:59.338351", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/kernel"}
- {"dt": "2023-11-30T03:59:59.347580", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/dtb"}
- {"dt": "2023-11-30T03:59:59.349191", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33923/nbd-deploy-t8za3u8n/nbdroot"}
- {"dt": "2023-11-30T03:59:59.632408", "lvl": "debug", "msg": "start: 11.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-11-30T03:59:59.633630", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-11-30T04:00:03.320445", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-30T04:00:03.364137", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-11-30T04:00:03.466292", "lvl": "debug", "msg": "end: 11.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-11-30T04:00:03.467249", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.83", "extra": {"status": "success"}, "level": "11.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-30T04:00:03.468959", "lvl": "debug", "msg": "start: 11.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-11-30T04:00:03.470827", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-30T04:00:03.472042", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.472979", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.473691", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.474336", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.474997", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.475653", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.476767", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.478392", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.480282", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.481072", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.481701", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.482320", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.483068", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.483846", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.484799", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.485427", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.485944", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.486438", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.486928", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.487440", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.487942", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.488572", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.489292", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.490155", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.491074", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.491875", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.492674", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.493316", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.494004", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.494572", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.495116", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.495784", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.496658", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.497542", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.498308", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.498973", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.499629", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.500384", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.501092", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.501749", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.502387", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.503032", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.503674", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.504387", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.505081", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.505747", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.506385", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.507030", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.507663", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-11-30T04:00:03.513077", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-30T04:00:04.472604", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-11-30T04:00:04.473574", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-11-30T04:00:04.474326", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-11-30T04:00:04.576409", "lvl": "debug", "msg": "end: 11.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-11-30T04:00:04.577538", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-11-30T04:00:04.578458", "lvl": "debug", "msg": "clean NBD port 61992"}
- {"dt": "2023-11-30T04:00:08.068562", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33923"}
- {"dt": "2023-11-30T04:00:08.458226", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33923"}
- {"dt": "2023-11-30T04:00:08.459984", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-11-30T04:00:08.461002", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
