- {"dt": "2023-12-13T09:02:30.350025", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-13T09:02:30.351384", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-13T09:02:30.352302", "lvl": "info", "msg": "Start time: 2023-12-13 09:02:30.352252+00:00 (UTC)"}
- {"dt": "2023-12-13T09:02:30.353298", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-13T09:02:30.574304", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-13T09:02:30.785303", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-13T09:02:31.005648", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-13T09:02:31.237698", "lvl": "info", "msg": "validate duration: 0.89"}
- {"dt": "2023-12-13T09:02:31.238492", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-13T09:02:31.239674", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:02:31.240344", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-13T09:02:31.240944", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-13T09:02:31.241501", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-13T09:02:31.242476", "lvl": "debug", "msg": "Set_port 61990"}
- {"dt": "2023-12-13T09:02:31.243406", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:02:31.244598", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-13T09:02:31.245831", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:02:31.246522", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:02:31.247217", "lvl": "debug", "msg": "total size: 2061277 (1MB)"}
- {"dt": "2023-12-13T09:02:31.247775", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:02:31.556358", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-13T09:02:31.752377", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-13T09:02:31.849154", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-13T09:02:31.862855", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-13T09:02:31.947448", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-13T09:02:31.959790", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-13T09:02:32.045232", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-13T09:02:32.050124", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-13T09:02:32.144549", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-13T09:02:32.156650", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-13T09:02:32.161149", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-13T09:02:32.249616", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-13T09:02:32.257326", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-13T09:02:32.346651", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-13T09:02:32.354214", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-13T09:02:32.440870", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-13T09:02:32.451852", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-13T09:02:32.537804", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-13T09:02:32.549451", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-13T09:02:32.553088", "lvl": "info", "msg": "1MB downloaded in 1.31s (1.51MB/s)"}
- {"dt": "2023-12-13T09:02:32.554309", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:02:32.554987", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.31", "extra": {"label": "initrd", "md5sum": "84f2818cd2af899dbb4f11f1e5bce056", "sha256sum": "7f476a604f0095be931de1469ebcfd958120a5e57f254a4d941da913f205e983", "sha512sum": "ece26991d0f37ce1850d3c644f14c99d8eaed8af6a45680c8b66657b23b4a086fb9a847b1e298d1762e5fd89ef3c0f16201e1bdb1edf195556a21e8d4ee3294e", "size": ! "2061277"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:02:32.556531", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:02:32.557323", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-13T09:02:32.558093", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-13T09:02:32.559203", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-13T09:02:32.559891", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/kernel/Image"}
- {"dt": "2023-12-13T09:02:32.560449", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-13T09:02:32.560981", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:02:32.869340", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-13T09:02:34.352789", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-13T09:02:35.811664", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-13T09:02:37.264423", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-13T09:02:38.550875", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-13T09:02:39.814709", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-13T09:02:40.958698", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-13T09:02:42.005272", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-13T09:02:42.878082", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-13T09:02:43.632563", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-13T09:02:44.234118", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-13T09:02:44.788391", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-13T09:02:45.243638", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-13T09:02:45.679520", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-13T09:02:46.055487", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-13T09:02:46.400432", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-13T09:02:46.719569", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-13T09:02:47.021342", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-13T09:02:47.288275", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-13T09:02:47.562723", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-13T09:02:47.790752", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-13T09:02:47.792640", "lvl": "info", "msg": "37MB downloaded in 15.23s (2.45MB/s)"}
- {"dt": "2023-12-13T09:02:47.793997", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:15) [common]"}
- {"dt": "2023-12-13T09:02:47.794795", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "15.24", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:02:47.796722", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:15) [common]"}
- {"dt": "2023-12-13T09:02:47.797658", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:43) [common]"}
- {"dt": "2023-12-13T09:02:47.798587", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:43) [common]"}
- {"dt": "2023-12-13T09:02:47.799859", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:02:47.800545", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:02:47.801101", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-13T09:02:47.801640", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-13T09:02:48.113953", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-13T09:02:48.215125", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-13T09:02:48.221875", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-13T09:02:48.226251", "lvl": "info", "msg": "0MB downloaded in 0.43s (0.19MB/s)"}
- {"dt": "2023-12-13T09:02:48.228208", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:02:48.228847", "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-12-13T09:02:48.230213", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:02:48.230845", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:43) [common]"}
- {"dt": "2023-12-13T09:02:48.231523", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:43) [common]"}
- {"dt": "2023-12-13T09:02:48.232465", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29523/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-13T09:02:48.233073", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:02:48.233530", "lvl": "debug", "msg": "total size: 454007716 (432MB)"}
- {"dt": "2023-12-13T09:02:48.233973", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-13T09:02:48.564834", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-13T09:03:01.824567", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-13T09:03:10.669126", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-13T09:03:15.696879", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-13T09:03:19.688700", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-13T09:03:24.225072", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-13T09:03:28.912420", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-13T09:03:34.710699", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-13T09:03:41.547841", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-13T09:03:47.586091", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-13T09:03:52.310503", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-13T09:03:57.481474", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-13T09:04:02.423853", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-13T09:04:06.851545", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-13T09:04:11.863508", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-13T09:04:16.487968", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-13T09:04:20.612652", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-13T09:04:24.809859", "lvl": "debug", "msg": "progress  85% (368MB)"}
- {"dt": "2023-12-13T09:04:28.933722", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-13T09:04:33.262461", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-13T09:04:40.643931", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-13T09:04:43.936017", "lvl": "info", "msg": "432MB downloaded in 115.70s (3.74MB/s)"}
- {"dt": "2023-12-13T09:04:43.940887", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:56) [common]"}
- {"dt": "2023-12-13T09:04:43.942986", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "115.71", "extra": {"label": "nbdroot", "md5sum": "6c0536bd817674dac124e7379d026057", "sha256sum": "121fa44e674bf690f27b46bfc78cd43cecdae8c27e4a3d03a5552ab96c8b8996", "sha512sum": "62105d782f7a066e2d7b3ea1735742587c198ae174eb06f61732593672704df7cc483125545dd87fad3745b03c09ca81a5638a401aada2a719aeef3e4c8f5edd", "size": ! "454007716"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:43.948012", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:56) [common]"}
- {"dt": "2023-12-13T09:04:43.948973", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.954013", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0"}
- {"dt": "2023-12-13T09:04:43.955323", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin"}
- {"dt": "2023-12-13T09:04:43.956317", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/tests"}
- {"dt": "2023-12-13T09:04:43.957196", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/results"}
- {"dt": "2023-12-13T09:04:43.958147", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-add-keys"}
- {"dt": "2023-12-13T09:04:43.959534", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-add-sources"}
- {"dt": "2023-12-13T09:04:43.960798", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-background-process-start"}
- {"dt": "2023-12-13T09:04:43.962020", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-background-process-stop"}
- {"dt": "2023-12-13T09:04:43.963263", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-common-functions"}
- {"dt": "2023-12-13T09:04:43.964493", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-echo-ipv4"}
- {"dt": "2023-12-13T09:04:43.965688", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-install-packages"}
- {"dt": "2023-12-13T09:04:43.966855", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-installed-packages"}
- {"dt": "2023-12-13T09:04:43.968087", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-os-build"}
- {"dt": "2023-12-13T09:04:43.969273", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-probe-channel"}
- {"dt": "2023-12-13T09:04:43.970440", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-probe-ip"}
- {"dt": "2023-12-13T09:04:43.971653", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-target-ip"}
- {"dt": "2023-12-13T09:04:43.972824", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-target-mac"}
- {"dt": "2023-12-13T09:04:43.973977", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-target-storage"}
- {"dt": "2023-12-13T09:04:43.975183", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-case"}
- {"dt": "2023-12-13T09:04:43.976387", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-event"}
- {"dt": "2023-12-13T09:04:43.977572", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-feedback"}
- {"dt": "2023-12-13T09:04:43.978728", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-raise"}
- {"dt": "2023-12-13T09:04:43.980058", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-reference"}
- {"dt": "2023-12-13T09:04:43.981261", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-runner"}
- {"dt": "2023-12-13T09:04:43.982420", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-set"}
- {"dt": "2023-12-13T09:04:43.983657", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-test-shell"}
- {"dt": "2023-12-13T09:04:43.984879", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-13T09:04:43.986268", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-13T09:04:43.987594", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/environment"}
- {"dt": "2023-12-13T09:04:43.988658", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-13T09:04:43.989315", "lvl": "debug", "msg": "- LAVA_JOB_ID=34211"}
- {"dt": "2023-12-13T09:04:43.989810", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-13T09:04:43.990681", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.992917", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:43.993775", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.994311", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-13T09:04:43.994865", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:43.995539", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.996073", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-13T09:04:43.996685", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:43.997334", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.997933", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-13T09:04:43.998663", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:47) [common]"}
- {"dt": "2023-12-13T09:04:43.999344", "lvl": "debug", "msg": "Using /lava-34211 at stage 0"}
- {"dt": "2023-12-13T09:04:44.000181", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:44.000834", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/0/tests/0_network-veth'"}
- {"dt": "2023-12-13T09:04:44.809592", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-13T09:04:44.816493", "lvl": "debug", "msg": "uuid=34211_1.5.4.1 testdef=None"}
- {"dt": "2023-12-13T09:04:44.818225", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:44.819551", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.82", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "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-12-13T09:04:44.822252", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:44.827634", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:44.828321", "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": "34211_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:44.829812", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:44.835866", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:44.836606", "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": "34211_1.5.4.1", "uuid": "34211_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:44.838162", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:44.844069", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/0/tests/0_network-veth test_uuid 34211_1.5.4.1"}
- {"dt": "2023-12-13T09:04:44.845686", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:44.846423", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/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": "34211_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:44.848069", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:44.848812", "lvl": "debug", "msg": "Using /lava-34211 at stage 1"}
- {"dt": "2023-12-13T09:04:44.849794", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:44.850543", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-13T09:04:45.482586", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/1_ci-busybox"}
- {"dt": "2023-12-13T09:04:45.497970", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-13T09:04:45.501289", "lvl": "debug", "msg": "uuid=34211_1.5.4.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-13T09:04:45.502400", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:45.503209", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:45.504679", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:45.510387", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:45.511056", "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": "34211_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:45.513144", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:45.520896", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:45.521620", "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": "34211_1.5.4.5", "uuid": "34211_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:45.523215", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:45.530765", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/1_ci-busybox test_uuid 34211_1.5.4.5"}
- {"dt": "2023-12-13T09:04:45.532428", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:45.533147", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/1_ci-busybox/run.sh", "from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34211_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:45.534690", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:46) [common]"}
- {"dt": "2023-12-13T09:04:45.535443", "lvl": "debug", "msg": "Using /lava-34211 at stage 1"}
- {"dt": "2023-12-13T09:04:45.536470", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:45.537227", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-13T09:04:46.158865", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-13T09:04:46.177171", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-13T09:04:46.180176", "lvl": "debug", "msg": "uuid=34211_1.5.4.9 testdef=None"}
- {"dt": "2023-12-13T09:04:46.181261", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:46.181998", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.183537", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-13T09:04:46.188758", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.189458", "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": "34211_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.190970", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-13T09:04:46.198333", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.199040", "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": "34211_1.5.4.9", "uuid": "34211_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.200668", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:45) [common]"}
- {"dt": "2023-12-13T09:04:46.207311", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/2_ci-smoke-tests-basic test_uuid 34211_1.5.4.9"}
- {"dt": "2023-12-13T09:04:46.210039", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.210768", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/2_ci-smoke-tests-basic/run.sh", "from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34211_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.212492", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:45) [common]"}
- {"dt": "2023-12-13T09:04:46.213231", "lvl": "debug", "msg": "Using /lava-34211 at stage 1"}
- {"dt": "2023-12-13T09:04:46.214235", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:46.214984", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-13T09:04:46.884583", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/3_ci-service-check"}
- {"dt": "2023-12-13T09:04:46.899068", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-13T09:04:46.903103", "lvl": "debug", "msg": "uuid=34211_1.5.4.13 testdef=None"}
- {"dt": "2023-12-13T09:04:46.904215", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:46.904929", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.69", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.906333", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:46.911597", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.912274", "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": "34211_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.913799", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:46.921427", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.922145", "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": "34211_1.5.4.13", "uuid": "34211_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.923749", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:46.930649", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/3_ci-service-check test_uuid 34211_1.5.4.13"}
- {"dt": "2023-12-13T09:04:46.931424", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-13T09:04:46.932024", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-13T09:04:46.933468", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:46.934182", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1/tests/3_ci-service-check/run.sh", "from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34211_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:46.935784", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:46.936507", "lvl": "debug", "msg": "Using /lava-34211 at stage 2"}
- {"dt": "2023-12-13T09:04:46.937511", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:46.938259", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/2/tests/4_screenshooter'"}
- {"dt": "2023-12-13T09:04:47.559861", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-13T09:04:47.568406", "lvl": "debug", "msg": "uuid=34211_1.5.4.17 testdef=None"}
- {"dt": "2023-12-13T09:04:47.569499", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:47.570253", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:47.571824", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:47.576438", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:47.577129", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34211_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:47.578654", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:47.584757", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:47.585466", "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": "34211_1.5.4.17", "uuid": "34211_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:47.587036", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:47.593058", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/2/tests/4_screenshooter test_uuid 34211_1.5.4.17"}
- {"dt": "2023-12-13T09:04:47.594650", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:47.595413", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/2/tests/4_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34211_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:47.597034", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:44) [common]"}
- {"dt": "2023-12-13T09:04:47.597754", "lvl": "debug", "msg": "Using /lava-34211 at stage 3"}
- {"dt": "2023-12-13T09:04:47.598758", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-13T09:04:47.599605", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-13T09:04:48.206899", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-13T09:04:48.224509", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-13T09:04:48.227740", "lvl": "debug", "msg": "uuid=34211_1.5.4.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-12-13T09:04:48.228847", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:48.229570", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:48.231002", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-13T09:04:48.236969", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.237636", "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": "34211_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:48.239131", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-13T09:04:48.246707", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.247605", "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": "34211_1.5.4.21", "uuid": "34211_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:48.249200", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-13T09:04:48.256592", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3/tests/5_ci-dumpjournal test_uuid 34211_1.5.4.21"}
- {"dt": "2023-12-13T09:04:48.258184", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.258975", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3/tests/5_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": "34211_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:48.260439", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-13T09:04:48.261055", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/0 for stage 0"}
- {"dt": "2023-12-13T09:04:48.262019", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-13T09:04:48.262883", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/1 for stage 1"}
- {"dt": "2023-12-13T09:04:48.264014", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-13T09:04:48.264690", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-13T09:04:48.265256", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-13T09:04:48.265960", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/2 for stage 2"}
- {"dt": "2023-12-13T09:04:48.266938", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-13T09:04:48.267819", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34211/lava-overlay-xc5t5_u0/lava-34211/3 for stage 3"}
- {"dt": "2023-12-13T09:04:48.268833", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-13T09:04:48.269860", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-13T09:04:48.270739", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:43) [common]"}
- {"dt": "2023-12-13T09:04:48.935963", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:04:48.936911", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:42) [common]"}
- {"dt": "2023-12-13T09:04:48.938201", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.939177", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:04:48.940152", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:42) [common]"}
- {"dt": "2023-12-13T09:04:48.941060", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:42) [common]"}
- {"dt": "2023-12-13T09:04:48.941882", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.942580", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.943335", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:42) [common]"}
- {"dt": "2023-12-13T09:04:48.943951", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-13T09:04:48.944525", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61990, NBD-ROOT: 34211/nbd-deploy-9su6vdag/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:04:48.948051", "lvl": "debug", "msg": "nbd-server 61990 /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-13T09:04:48.972362", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-13T09:04:48.974177", "lvl": "debug", "msg": "output: ** (process:7565): WARNING **: 09:04:48.970: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-13T09:04:48.974764", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-13T09:04:48.975449", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-13T09:04:48.977239", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.978788", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:18) [common]"}
- {"dt": "2023-12-13T09:04:48.979984", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:04:48.980729", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:04:48.981383", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.982037", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:04:48.983237", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-13T09:04:48.983873", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:04:48.984386", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-13T09:04:48.984834", "lvl": "debug", "msg": "- {DTB}: 34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:04:48.985287", "lvl": "debug", "msg": "- {INITRD}: 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:04:48.985739", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-13T09:04:48.986164", "lvl": "debug", "msg": "- {KERNEL}: 34211/nbd-deploy-9su6vdag/kernel/Image"}
- {"dt": "2023-12-13T09:04:48.986590", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-13T09:04:48.987038", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-13T09:04:48.987556", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61990"}
- {"dt": "2023-12-13T09:04:48.988003", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-13T09:04:48.988434", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-13T09:04:48.988860", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-13T09:04:48.989302", "lvl": "debug", "msg": "- {RAMDISK}: 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:04:48.989735", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-13T09:04:48.990162", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-13T09:04:48.990585", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-13T09:04:48.991016", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-13T09:04:48.991500", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-13T09:04:48.991938", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-13T09:04:48.992355", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-13T09:04:48.992776", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:48.993212", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:48.993658", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-13T09:04:48.994078", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:04:48.994492", "lvl": "info", "msg": "- tftp 0x68080000 34211/nbd-deploy-9su6vdag/kernel/Image"}
- {"dt": "2023-12-13T09:04:48.994908", "lvl": "info", "msg": "- tftp 0x4a000000 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:04:48.995396", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:04:48.995837", "lvl": "info", "msg": "- tftp 0x68000000 34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:04:48.996265", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:04:48.996732", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:04:48.997328", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-13T09:04:48.997886", "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-12-13T09:04:48.999323", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-13T09:04:48.999963", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-13T09:04:52.139396", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-13T09:04:52.145314", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-13T09:04:52.147810", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:04:52.148671", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:04:52.149498", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-13T09:04:52.150769", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-13T09:04:55.589893", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:04:56.663292", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:04:56.699048", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-13T09:04:56.802660", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:04:56.804989", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.65", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:04:56.809166", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-13T09:04:56.811721", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-13T09:04:56.813931", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-13T09:04:56.815717", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-13T09:04:56.817446", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-13T09:04:56.818133", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-13T09:04:56.818676", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-13T09:04:56.867855", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Pr\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:56.869795", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:56.915713", "lvl": "target", "msg": "NOTICE:  BL2: Board is Sta\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:56.917788", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:56.920435", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.000675", "lvl": "target", "msg": "NOTICE:  BL\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.002739", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.044568", "lvl": "target", "msg": "NOTICE:  BL2: Board \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.046508", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.092842", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.094783", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.097042", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.144286", "lvl": "target", "msg": "NOTICE:  BL2\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.146307", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.148219", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.199451", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.201290", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.203720", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.204445", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:04:57.204916", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-13T09:04:57.267947", "lvl": "target", "msg": "NOTICE:  AVS\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.269846", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.271603", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.273614", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:04:57.275403", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-13T09:04:57.277112", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-13T09:04:57.278792", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-13T09:04:57.341321", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-13T09:04:57.342467", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-13T09:04:57.343574", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-13T09:04:57.344815", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-13T09:04:57.345880", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-13T09:04:57.346924", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-13T09:04:57.348051", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-13T09:04:57.349309", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-13T09:04:57.386795", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-13T09:04:57.389010", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-13T09:04:57.390409", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-13T09:04:57.391902", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-13T09:04:57.393454", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:57.394951", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:57.396528", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:57.398060", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:57.399536", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-13T09:04:57.400169", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-13T09:04:57.400808", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-13T09:04:57.429611", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-13T09:04:57.431254", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-13T09:04:57.432330", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-13T09:04:57.433317", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-13T09:04:57.434283", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-13T09:04:57.435343", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-13T09:04:57.436496", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-13T09:04:57.469687", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-13T09:04:57.471716", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-13T09:04:57.473566", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-13T09:04:57.802264", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:04:57.804304", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:04:57.806169", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-13T09:04:57.808052", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:04:57.810526", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-13T09:04:57.812491", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-13T09:04:57.996260", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-13T09:04:58.020711", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-13T09:04:58.022306", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-13T09:04:58.024605", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:58.026206", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:58.027807", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-13T09:04:58.029441", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:04:58.074575", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-13T09:04:58.240498", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-13T09:04:58.242377", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-13T09:04:58.244193", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-13T09:04:58.246717", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-13T09:04:58.290386", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-13T09:04:58.307102", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-13T09:04:58.359208", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-13T09:04:58.361877", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-13T09:04:58.364428", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-12-13T09:04:58.366472", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-13T09:04:58.368660", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-12-13T09:04:58.386275", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-13T09:04:58.389160", "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-12-13T09:04:58.390900", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-13T09:04:58.493500", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-13T09:04:58.495790", "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:50)"}
- {"dt": "2023-12-13T09:04:58.514041", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-13T09:04:58.515035", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-13T09:04:58.616594", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:58.618802", "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:50)"}
- {"dt": "2023-12-13T09:04:58.626159", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:58.628109", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-13T09:04:58.729565", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:58.731526", "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:50)"}
- {"dt": "2023-12-13T09:04:58.737853", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-13T09:04:58.740049", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-13T09:04:58.842652", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-13T09:04:58.845061", "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:50)"}
- {"dt": "2023-12-13T09:04:58.849638", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-13T09:05:01.937648", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-13T09:05:01.954469", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-13T09:05:02.192932", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-13T09:05:02.704226", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-13T09:05:02.991667", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1034 ms)"}
- {"dt": "2023-12-13T09:05:02.993062", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-13T09:05:03.094885", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:05:03.095934", "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:46)"}
- {"dt": "2023-12-13T09:05:03.104195", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-13T09:05:03.105535", "lvl": "input", "msg": "tftp 0x68080000 34211/nbd-deploy-9su6vdag/kernel/Image\n"}
- {"dt": "2023-12-13T09:05:03.207262", "lvl": "target", "msg": "=> tftp 0x68080000 34211/nbd-deploy-9su6vdag/kernel/Image"}
- {"dt": "2023-12-13T09:05:03.208226", "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:46)"}
- {"dt": "2023-12-13T09:05:03.215922", "lvl": "target", "msg": "tftp 0x68080000 34211/nbd-deploy-9su6vdag/kernel/Image"}
- {"dt": "2023-12-13T09:05:03.252056", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:05:03.252916", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:05:03.255685", "lvl": "target", "msg": "Filename '34211/nbd-deploy-9su6vdag/kernel/Image'."}
- {"dt": "2023-12-13T09:05:03.256826", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-13T09:05:03.749013", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-13T09:05:04.261188", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:04.771203", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:05.282279", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:05.794475", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:06.288342", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:06.800046", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:07.326430", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:07.837810", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:08.364524", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:08.875417", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:09.386787", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:09.913517", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:10.408666", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:10.919875", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:11.431273", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:11.941620", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:12.452854", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:12.964071", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:13.474815", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:14.001997", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:14.513258", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:15.023849", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:15.535038", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:16.045989", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:16.557181", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:17.067927", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:17.594898", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:18.105977", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:18.634137", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:19.160288", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:19.670866", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:20.182134", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:20.709835", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:21.236295", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:21.810816", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:22.385917", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:22.945027", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:23.519985", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:24.094598", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:24.653538", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:24.701657", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-13T09:05:24.703714", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-13T09:05:24.705483", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:05:24.717621", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-13T09:05:24.719472", "lvl": "input", "msg": "tftp 0x4a000000 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-13T09:05:24.821468", "lvl": "target", "msg": "=> tftp 0x4a000000 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:05:24.823420", "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:24)"}
- {"dt": "2023-12-13T09:05:24.828187", "lvl": "target", "msg": "tftp 0x4a000000 34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-13T09:05:24.860586", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:05:24.862119", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:05:24.864246", "lvl": "target", "msg": "Filename '34211/nbd-deploy-9su6vdag/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-13T09:05:24.865691", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-13T09:05:25.437718", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-13T09:05:26.012758", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-13T09:05:26.112458", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-13T09:05:26.114354", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-13T09:05:26.116176", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:05:26.117922", "lvl": "target", "msg": "Bytes transferred = 2061277 (1f73dd hex)"}
- {"dt": "2023-12-13T09:05:26.121170", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-13T09:05:26.223890", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:05:26.226068", "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:23)"}
- {"dt": "2023-12-13T09:05:26.242580", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-13T09:05:26.244384", "lvl": "input", "msg": "tftp 0x68000000 34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-13T09:05:26.346719", "lvl": "target", "msg": "=> tftp 0x68000000 34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:05:26.349068", "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:23)"}
- {"dt": "2023-12-13T09:05:26.353344", "lvl": "target", "msg": "tftp 0x68000000 34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-13T09:05:26.385576", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-13T09:05:26.387991", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-13T09:05:26.389329", "lvl": "target", "msg": "Filename '34211/nbd-deploy-9su6vdag/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-13T09:05:26.390546", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-13T09:05:26.436433", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-13T09:05:26.438335", "lvl": "target", "msg": "\t 1.3 MiB/s"}
- {"dt": "2023-12-13T09:05:26.440238", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-13T09:05:26.452222", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-13T09:05:26.453599", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-12-13T09:05:26.555638", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:05:26.557769", "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:22)"}
- {"dt": "2023-12-13T09:05:26.574013", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-12-13T09:05:26.575871", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-13T09:05:26.678200", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:05:26.680594", "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-12-13T09:05:26.682552", "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:22)"}
- {"dt": "2023-12-13T09:05:26.686607", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-13T09:05:26.701581", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-13T09:05:26.847881", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-13T09:05:26.850200", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-13T09:05:26.852212", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff3dd ... OK"}
- {"dt": "2023-12-13T09:05:26.853946", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000042"}
- {"dt": "2023-12-13T09:05:26.855816", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-13T09:05:26.857631", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-13T09:05:26.859486", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-13T09:05:26.861266", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-13T09:05:26.863337", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-13T09:05:26.893018", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-13T09:05:26.894565", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-13T09:05:26.895692", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-13T09:05:26.896705", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-13T09:05:26.897679", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-13T09:05:26.898647", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-13T09:05:26.899683", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-13T09:05:26.900664", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-13T09:05:26.901636", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-13T09:05:26.902796", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-13T09:05:26.903725", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-13T09:05:26.913913", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-13T09:05:26.914842", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:05:26.917562", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-13T09:05:26.918547", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-13T09:05:26.919509", "lvl": "target", "msg": ""}
- {"dt": "2023-12-13T09:05:26.921075", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:05:26.922143", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:29) [common]"}
- {"dt": "2023-12-13T09:05:26.923123", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "28.56", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:05:26.925106", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:05:26.926191", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:35) [common]"}
- {"dt": "2023-12-13T09:05:26.927394", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "34.78", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:05:26.928544", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-13T09:05:26.929304", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-13T09:05:26.929963", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:38) [common]"}
- {"dt": "2023-12-13T09:05:26.930593", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "37.95", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-13T09:05:26.931977", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-13T09:05:26.932768", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/initrd"}
- {"dt": "2023-12-13T09:05:26.934679", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/kernel"}
- {"dt": "2023-12-13T09:05:26.944396", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/dtb"}
- {"dt": "2023-12-13T09:05:26.945848", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34211/nbd-deploy-9su6vdag/nbdroot"}
- {"dt": "2023-12-13T09:05:27.625049", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-13T09:05:27.626201", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-13T09:05:31.076669", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-13T09:05:31.118984", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-13T09:05:31.221962", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-13T09:05:31.224274", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.60", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-13T09:05:31.228092", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-13T09:05:31.232424", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-13T09:05:31.235054", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.236115", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.236711", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.237238", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.237750", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.238251", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.238743", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.239283", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.239797", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.240285", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.240770", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.241251", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.241728", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.242207", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.242686", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.243195", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.243685", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.244184", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.244666", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.245159", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.245696", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.246184", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.246660", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.247159", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.247681", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.248194", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.248711", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.249218", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.249706", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.250187", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.250671", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.251188", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.251671", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.252145", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.252619", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.253093", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.253563", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.254032", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.254500", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.254968", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.255495", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.255982", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.256455", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.256930", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.257407", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.257882", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.258356", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.258834", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.259343", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-13T09:05:31.260159", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-13T09:05:32.235611", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-13T09:05:32.237236", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-13T09:05:32.238614", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-13T09:05:32.341805", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-13T09:05:32.344078", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-13T09:05:32.345901", "lvl": "debug", "msg": "clean NBD port 61990"}
- {"dt": "2023-12-13T09:05:35.580002", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34211"}
- {"dt": "2023-12-13T09:05:35.674859", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34211"}
- {"dt": "2023-12-13T09:05:35.676012", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-13T09:05:35.676618", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
