- {"dt": "2023-12-08T17:49:33.205277", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-08T17:49:33.206534", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-08T17:49:33.207561", "lvl": "info", "msg": "Start time: 2023-12-08 17:49:33.207506+00:00 (UTC)"}
- {"dt": "2023-12-08T17:49:33.208702", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-08T17:49:33.430811", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-08T17:49:33.638854", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-08T17:49:33.855623", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-08T17:49:34.090797", "lvl": "info", "msg": "validate duration: 0.88"}
- {"dt": "2023-12-08T17:49:34.091627", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-08T17:49:34.092786", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-08T17:49:34.093452", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-08T17:49:34.094082", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-08T17:49:34.094650", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-08T17:49:34.095701", "lvl": "debug", "msg": "Set_port 61974"}
- {"dt": "2023-12-08T17:49:34.096593", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-08T17:49:34.097394", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-08T17:49:34.098624", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:49:34.099356", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:49:34.099983", "lvl": "debug", "msg": "total size: 2062770 (1MB)"}
- {"dt": "2023-12-08T17:49:34.100547", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-08T17:49:34.414764", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-08T17:49:34.614277", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-08T17:49:34.712626", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-08T17:49:34.720884", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-08T17:49:34.812266", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-08T17:49:34.822052", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-08T17:49:34.830069", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-08T17:49:34.834449", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-08T17:49:34.911016", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-08T17:49:34.918837", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-08T17:49:34.924815", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-08T17:49:34.930369", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-08T17:49:34.934729", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-08T17:49:34.940280", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-08T17:49:35.013469", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-08T17:49:35.026057", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-08T17:49:35.032552", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-08T17:49:35.036930", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-08T17:49:35.042452", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-08T17:49:35.046020", "lvl": "info", "msg": "1MB downloaded in 0.95s (2.08MB/s)"}
- {"dt": "2023-12-08T17:49:35.047299", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:49:35.047991", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.95", "extra": {"label": "initrd", "md5sum": "5b2757fbd60c47aced86694ac33c9181", "sha256sum": "78901fe4aaebb0d3384922f6b40f45c66448cba67d96e819d98155ce735be15a", "sha512sum": "f71f32d384e57c8f7978eed6e48aef52859784760561f5cd42164385ad3ab47897b037ef1bf2b2630bbbac6f8695a28da37c32c0c1dd20b8b51a8e9b1c12435b", "size": ! "2062770"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:49:35.049502", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:49:35.050297", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-08T17:49:35.051075", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-08T17:49:35.052209", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-08T17:49:35.052889", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/kernel/Image"}
- {"dt": "2023-12-08T17:49:35.053452", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-08T17:49:35.053999", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-08T17:49:35.361284", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-08T17:49:35.969723", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-08T17:49:36.343410", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-08T17:49:36.650750", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-08T17:49:36.950849", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-08T17:49:37.243629", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-08T17:49:37.525132", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-08T17:49:37.757068", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-08T17:49:38.045866", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-08T17:49:38.310859", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-08T17:49:38.544114", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-08T17:49:38.749568", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-08T17:49:39.013969", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-08T17:49:39.225545", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-08T17:49:39.426945", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-08T17:49:39.626197", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-08T17:49:39.821712", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-08T17:49:40.013794", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-08T17:49:40.198001", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-08T17:49:40.371761", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-08T17:49:40.520375", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-08T17:49:40.521916", "lvl": "info", "msg": "37MB downloaded in 5.47s (6.84MB/s)"}
- {"dt": "2023-12-08T17:49:40.523048", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:05) [common]"}
- {"dt": "2023-12-08T17:49:40.523767", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "5.47", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:49:40.525236", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:05) [common]"}
- {"dt": "2023-12-08T17:49:40.526016", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-12-08T17:49:40.526788", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-12-08T17:49:40.527929", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:49:40.528613", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:49:40.529183", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-08T17:49:40.529740", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-08T17:49:40.839845", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-08T17:49:40.940278", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-08T17:49:40.944599", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-08T17:49:40.947354", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-12-08T17:49:40.949711", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:49:40.950927", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:49:40.952828", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:49:40.953628", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:53) [common]"}
- {"dt": "2023-12-08T17:49:40.954476", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:53) [common]"}
- {"dt": "2023-12-08T17:49:40.955604", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29511/3/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-08T17:49:40.956301", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-08T17:49:40.956922", "lvl": "debug", "msg": "total size: 453710060 (432MB)"}
- {"dt": "2023-12-08T17:49:40.957585", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-08T17:49:41.276897", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-08T17:49:47.067004", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-08T17:49:51.386223", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-08T17:49:56.713280", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-08T17:50:01.172569", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-08T17:50:07.813236", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-08T17:50:13.855725", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-08T17:50:21.164540", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-08T17:50:28.907949", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-08T17:50:34.764937", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-08T17:50:39.843687", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-08T17:50:45.635355", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-08T17:50:50.771354", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-08T17:50:55.265085", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-08T17:51:00.302301", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-12-08T17:51:05.175250", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-08T17:51:09.262593", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-08T17:51:13.678230", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-08T17:51:18.308967", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-08T17:51:22.505965", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-08T17:51:29.937865", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-08T17:51:33.093201", "lvl": "info", "msg": "432MB downloaded in 112.14s (3.86MB/s)"}
- {"dt": "2023-12-08T17:51:33.095032", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:52) [common]"}
- {"dt": "2023-12-08T17:51:33.095939", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "112.14", "extra": {"label": "nbdroot", "md5sum": "9533cc60ecbef835d623a6775ea81ea9", "sha256sum": "a57e9892098d9651cb5f492bb813169653645f4f95b71db36d4473b6b5b75c0d", "sha512sum": "6f66b2319b6f12d2eaa3252fdab0c7fd9fb7a923765cf805b41f2058d5e5c663abd2725ed54a83860336c954e31193170ccefdbc34c08098b5d921e7783db0b7", "size": ! "453710060"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:33.097688", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:52) [common]"}
- {"dt": "2023-12-08T17:51:33.098656", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.100358", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1"}
- {"dt": "2023-12-08T17:51:33.101582", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin"}
- {"dt": "2023-12-08T17:51:33.102531", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/tests"}
- {"dt": "2023-12-08T17:51:33.103492", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/results"}
- {"dt": "2023-12-08T17:51:33.104452", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-add-keys"}
- {"dt": "2023-12-08T17:51:33.105945", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-add-sources"}
- {"dt": "2023-12-08T17:51:33.107296", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-background-process-start"}
- {"dt": "2023-12-08T17:51:33.108631", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-background-process-stop"}
- {"dt": "2023-12-08T17:51:33.109889", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-common-functions"}
- {"dt": "2023-12-08T17:51:33.111365", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-echo-ipv4"}
- {"dt": "2023-12-08T17:51:33.112714", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-install-packages"}
- {"dt": "2023-12-08T17:51:33.113974", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-installed-packages"}
- {"dt": "2023-12-08T17:51:33.115298", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-os-build"}
- {"dt": "2023-12-08T17:51:33.116621", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-probe-channel"}
- {"dt": "2023-12-08T17:51:33.118044", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-probe-ip"}
- {"dt": "2023-12-08T17:51:33.119400", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-target-ip"}
- {"dt": "2023-12-08T17:51:33.120659", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-target-mac"}
- {"dt": "2023-12-08T17:51:33.121913", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-target-storage"}
- {"dt": "2023-12-08T17:51:33.123366", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-case"}
- {"dt": "2023-12-08T17:51:33.124719", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-event"}
- {"dt": "2023-12-08T17:51:33.125997", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-feedback"}
- {"dt": "2023-12-08T17:51:33.127292", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-raise"}
- {"dt": "2023-12-08T17:51:33.128647", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-reference"}
- {"dt": "2023-12-08T17:51:33.130082", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-runner"}
- {"dt": "2023-12-08T17:51:33.131415", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-set"}
- {"dt": "2023-12-08T17:51:33.132677", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-test-shell"}
- {"dt": "2023-12-08T17:51:33.133972", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-08T17:51:33.135641", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-08T17:51:33.136973", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/environment"}
- {"dt": "2023-12-08T17:51:33.138055", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-08T17:51:33.138727", "lvl": "debug", "msg": "- LAVA_JOB_ID=34075"}
- {"dt": "2023-12-08T17:51:33.139308", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-08T17:51:33.140235", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.142718", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.143618", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.144223", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-08T17:51:33.144828", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.145489", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.145994", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-08T17:51:33.146772", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.147750", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.148464", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-08T17:51:33.149231", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:01) [common]"}
- {"dt": "2023-12-08T17:51:33.149858", "lvl": "debug", "msg": "Using /lava-34075 at stage 0"}
- {"dt": "2023-12-08T17:51:33.150684", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:33.151408", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/0/tests/0_network-veth'"}
- {"dt": "2023-12-08T17:51:33.957911", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-08T17:51:33.962765", "lvl": "debug", "msg": "uuid=34075_1.5.4.1 testdef=None"}
- {"dt": "2023-12-08T17:51:33.964156", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:33.965097", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.81", "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-08T17:51:33.966953", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-12-08T17:51:33.972772", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.973455", "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": "34075_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:33.974980", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-12-08T17:51:33.981124", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.981837", "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": "34075_1.5.4.1", "uuid": "34075_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:33.983440", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-12-08T17:51:33.989266", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/0/tests/0_network-veth test_uuid 34075_1.5.4.1"}
- {"dt": "2023-12-08T17:51:33.990813", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:33.991586", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:33.993069", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:28:00) [common]"}
- {"dt": "2023-12-08T17:51:33.993685", "lvl": "debug", "msg": "Using /lava-34075 at stage 1"}
- {"dt": "2023-12-08T17:51:33.994565", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:33.995267", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-08T17:51:34.590946", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/1_ci-busybox"}
- {"dt": "2023-12-08T17:51:34.601811", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-08T17:51:34.605503", "lvl": "debug", "msg": "uuid=34075_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-08T17:51:34.606667", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:34.607622", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-08T17:51:34.609205", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:34.616051", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:34.616890", "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": "34075_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:34.618461", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:34.627003", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:34.627823", "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": "34075_1.5.4.5", "uuid": "34075_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:34.629394", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:34.637779", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/1_ci-busybox test_uuid 34075_1.5.4.5"}
- {"dt": "2023-12-08T17:51:34.639765", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:34.640517", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:34.642110", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:34.642841", "lvl": "debug", "msg": "Using /lava-34075 at stage 1"}
- {"dt": "2023-12-08T17:51:34.643956", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:34.644733", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-08T17:51:35.238511", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-08T17:51:35.249073", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-08T17:51:35.252074", "lvl": "debug", "msg": "uuid=34075_1.5.4.9 testdef=None"}
- {"dt": "2023-12-08T17:51:35.253171", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:35.253916", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-08T17:51:35.255990", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:35.261445", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.262184", "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": "34075_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.263813", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:35.271130", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.271918", "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": "34075_1.5.4.9", "uuid": "34075_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.273527", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:35.280530", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/2_ci-smoke-tests-basic test_uuid 34075_1.5.4.9"}
- {"dt": "2023-12-08T17:51:35.283443", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.284196", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.285813", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-08T17:51:35.286581", "lvl": "debug", "msg": "Using /lava-34075 at stage 1"}
- {"dt": "2023-12-08T17:51:35.287679", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:35.288479", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-08T17:51:35.839543", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/3_ci-service-check"}
- {"dt": "2023-12-08T17:51:35.850686", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-08T17:51:35.854990", "lvl": "debug", "msg": "uuid=34075_1.5.4.13 testdef=None"}
- {"dt": "2023-12-08T17:51:35.856116", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:35.856852", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.57", "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-08T17:51:35.858265", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:35.864188", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.865000", "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": "34075_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.866493", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:35.873709", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.874404", "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": "34075_1.5.4.13", "uuid": "34075_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.875909", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:35.882801", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1/tests/3_ci-service-check test_uuid 34075_1.5.4.13"}
- {"dt": "2023-12-08T17:51:35.883566", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-08T17:51:35.884242", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-08T17:51:35.885669", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:35.886345", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:35.887882", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:35.888531", "lvl": "debug", "msg": "Using /lava-34075 at stage 2"}
- {"dt": "2023-12-08T17:51:35.889444", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:35.890090", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/2/tests/4_screenshooter'"}
- {"dt": "2023-12-08T17:51:36.457161", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-08T17:51:36.460775", "lvl": "debug", "msg": "uuid=34075_1.5.4.17 testdef=None"}
- {"dt": "2023-12-08T17:51:36.461939", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:36.462732", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.57", "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-08T17:51:36.464279", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:36.469068", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:36.469754", "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": "34075_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:36.471278", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:36.477459", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:36.478159", "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": "34075_1.5.4.17", "uuid": "34075_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:36.479787", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:36.485718", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/2/tests/4_screenshooter test_uuid 34075_1.5.4.17"}
- {"dt": "2023-12-08T17:51:36.487407", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:36.488123", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:36.489576", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:58) [common]"}
- {"dt": "2023-12-08T17:51:36.490199", "lvl": "debug", "msg": "Using /lava-34075 at stage 3"}
- {"dt": "2023-12-08T17:51:36.491106", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-08T17:51:36.491872", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-08T17:51:37.234223", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-08T17:51:37.244861", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-08T17:51:37.248212", "lvl": "debug", "msg": "uuid=34075_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-08T17:51:37.249450", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:37.250173", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.76", "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-08T17:51:37.251568", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-08T17:51:37.257106", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.257815", "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": "34075_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:37.259231", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-08T17:51:37.267190", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.267894", "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": "34075_1.5.4.21", "uuid": "34075_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:37.269354", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-08T17:51:37.276581", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/3/tests/5_ci-dumpjournal test_uuid 34075_1.5.4.21"}
- {"dt": "2023-12-08T17:51:37.278181", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.278855", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/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": "34075_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:37.280242", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-08T17:51:37.280746", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/0 for stage 0"}
- {"dt": "2023-12-08T17:51:37.281579", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-08T17:51:37.282366", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/1 for stage 1"}
- {"dt": "2023-12-08T17:51:37.283368", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-08T17:51:37.284050", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-08T17:51:37.284537", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-08T17:51:37.285155", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/2 for stage 2"}
- {"dt": "2023-12-08T17:51:37.286008", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-08T17:51:37.286725", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34075/lava-overlay-n8rmjvr1/lava-34075/3 for stage 3"}
- {"dt": "2023-12-08T17:51:37.287666", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-08T17:51:37.288736", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-08T17:51:37.289561", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-08T17:51:37.989388", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:51:37.990290", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-08T17:51:37.991552", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.992357", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-08T17:51:37.993048", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:56) [common]"}
- {"dt": "2023-12-08T17:51:37.993752", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:56) [common]"}
- {"dt": "2023-12-08T17:51:37.994618", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.995346", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:37.996077", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:56) [common]"}
- {"dt": "2023-12-08T17:51:37.996759", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-08T17:51:37.997404", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61974, NBD-ROOT: 34075/nbd-deploy-uz72iu7c/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-08T17:51:38.001020", "lvl": "debug", "msg": "nbd-server 61974 /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-08T17:51:38.021039", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-08T17:51:38.022906", "lvl": "debug", "msg": "output: ** (process:836): WARNING **: 17:51:38.019: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-08T17:51:38.024053", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-08T17:51:38.024885", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-08T17:51:38.026003", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:38.027047", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:04) [common]"}
- {"dt": "2023-12-08T17:51:38.028147", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-08T17:51:38.029183", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-08T17:51:38.030071", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:38.030889", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-08T17:51:38.032265", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-08T17:51:38.033074", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-08T17:51:38.033677", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-08T17:51:38.034197", "lvl": "debug", "msg": "- {DTB}: 34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:51:38.034646", "lvl": "debug", "msg": "- {INITRD}: 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:51:38.035243", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-08T17:51:38.035828", "lvl": "debug", "msg": "- {KERNEL}: 34075/nbd-deploy-uz72iu7c/kernel/Image"}
- {"dt": "2023-12-08T17:51:38.036413", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-08T17:51:38.037059", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-08T17:51:38.037706", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61974"}
- {"dt": "2023-12-08T17:51:38.038255", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-08T17:51:38.038784", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-08T17:51:38.039535", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-08T17:51:38.040154", "lvl": "debug", "msg": "- {RAMDISK}: 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:51:38.040641", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-08T17:51:38.041114", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-08T17:51:38.041558", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-08T17:51:38.041992", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-08T17:51:38.042420", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-08T17:51:38.042921", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-08T17:51:38.043608", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-08T17:51:38.044187", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:38.044736", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:38.045298", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-08T17:51:38.045841", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-08T17:51:38.046359", "lvl": "info", "msg": "- tftp 0x68080000 34075/nbd-deploy-uz72iu7c/kernel/Image"}
- {"dt": "2023-12-08T17:51:38.046884", "lvl": "info", "msg": "- tftp 0x4a000000 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:51:38.047610", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-08T17:51:38.048290", "lvl": "info", "msg": "- tftp 0x68000000 34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:51:38.048813", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61974 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-08T17:51:38.049348", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-08T17:51:38.049972", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-08T17:51:38.050615", "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-08T17:51:38.052252", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-08T17:51:38.052950", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-08T17:51:41.214796", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-08T17:51:41.217181", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-08T17:51:41.219209", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-08T17:51:41.220144", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-08T17:51:41.220923", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-08T17:51:41.222108", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-08T17:51:44.873479", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-08T17:51:45.946433", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-08T17:51:45.979893", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-08T17:51:46.082223", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-08T17:51:46.083194", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.86", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:51:46.085257", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-08T17:51:46.086159", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-08T17:51:46.086914", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-08T17:51:46.088411", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-08T17:51:46.090126", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-08T17:51:46.091725", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-08T17:51:46.092504", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-08T17:51:46.156876", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 In\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.158061", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.199656", "lvl": "target", "msg": "NOTICE:  BL2:\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.200438", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.302525", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Re\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program L\uFFFD\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.344561", "lvl": "target", "msg": "NOTICE:  BL2: PRR is \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.345893", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.391701", "lvl": "target", "msg": "NOTICE:  BL2: Board is S\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.393276", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.440097", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier R\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.441236", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.443368", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-08T17:51:46.491928", "lvl": "target", "msg": "NOTICE:  BL2: Bo\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.493225", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.494361", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-08T17:51:46.547031", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.548294", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.549354", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-08T17:51:46.551111", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-08T17:51:46.552293", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-08T17:51:46.612298", "lvl": "target", "msg": "NOTICE: \uFFFD\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.613434", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.614868", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-08T17:51:46.616179", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-08T17:51:46.617202", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-08T17:51:46.680916", "lvl": "target", "msg": "N\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-08T17:51:46.682309", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-08T17:51:46.683651", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-08T17:51:46.685010", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-08T17:51:46.686323", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-08T17:51:46.687586", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-08T17:51:46.688826", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-08T17:51:46.689579", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-08T17:51:46.726076", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-08T17:51:46.727605", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-08T17:51:46.728520", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-08T17:51:46.729320", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-08T17:51:46.730212", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:46.731044", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:46.731953", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:46.732795", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:46.733627", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-08T17:51:46.734450", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-08T17:51:46.735491", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-08T17:51:46.769084", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-08T17:51:46.770580", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-08T17:51:46.771826", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-08T17:51:46.773040", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-08T17:51:46.774185", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-08T17:51:46.775324", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-08T17:51:46.776621", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-08T17:51:46.809414", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-08T17:51:46.810817", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-08T17:51:46.813338", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-08T17:51:47.141768", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:51:47.143623", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:51:47.145377", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-08T17:51:47.146830", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:51:47.148604", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-08T17:51:47.149838", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-08T17:51:47.335685", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-08T17:51:47.360387", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-08T17:51:47.362106", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-08T17:51:47.364551", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:47.365984", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:47.367317", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-08T17:51:47.368583", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:51:47.413919", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-08T17:51:47.579640", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-08T17:51:47.580635", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-08T17:51:47.581391", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-08T17:51:47.583002", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-08T17:51:47.630063", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-08T17:51:47.646721", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-08T17:51:47.698854", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-08T17:51:47.701699", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-08T17:51:47.704062", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:50) [common]"}
- {"dt": "2023-12-08T17:51:47.705917", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-08T17:51:47.707823", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:50)"}
- {"dt": "2023-12-08T17:51:47.726847", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-08T17:51:47.729120", "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-08T17:51:47.730333", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-08T17:51:47.832819", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-08T17:51:47.834886", "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-08T17:51:47.854147", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-08T17:51:47.855240", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-08T17:51:47.956720", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:47.958517", "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-08T17:51:47.965548", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:47.967497", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-08T17:51:48.068989", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:48.070770", "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-08T17:51:48.077270", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-08T17:51:48.079159", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-08T17:51:48.180622", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-08T17:51:48.182391", "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-08T17:51:48.188996", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-08T17:51:51.286797", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-08T17:51:51.288652", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-08T17:51:51.542375", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-08T17:51:52.037530", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-08T17:51:52.324090", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1028 ms)"}
- {"dt": "2023-12-08T17:51:52.325141", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-08T17:51:52.426732", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-08T17:51:52.428406", "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-08T17:51:52.436343", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-08T17:51:52.439800", "lvl": "input", "msg": "tftp 0x68080000 34075/nbd-deploy-uz72iu7c/kernel/Image\n"}
- {"dt": "2023-12-08T17:51:52.541405", "lvl": "target", "msg": "=> tftp 0x68080000 34075/nbd-deploy-uz72iu7c/kernel/Image"}
- {"dt": "2023-12-08T17:51:52.542250", "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:45)"}
- {"dt": "2023-12-08T17:51:52.547667", "lvl": "target", "msg": "tftp 0x68080000 34075/nbd-deploy-uz72iu7c/kernel/Image"}
- {"dt": "2023-12-08T17:51:52.583438", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-08T17:51:52.584707", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-08T17:51:52.587018", "lvl": "target", "msg": "Filename '34075/nbd-deploy-uz72iu7c/kernel/Image'."}
- {"dt": "2023-12-08T17:51:52.587884", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-08T17:51:53.065655", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-08T17:51:53.592615", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:54.135713", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:54.677655", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:55.204868", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:55.731995", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:56.275673", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:56.818300", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:57.360995", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:57.904620", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:58.414147", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:58.894177", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:59.341242", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:51:59.803697", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:00.282907", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:00.778116", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:01.241314", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:01.720232", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:02.200153", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:02.727721", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:03.224194", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:03.719445", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:04.182520", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:04.647390", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:05.173907", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:05.734213", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:06.374120", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:07.572398", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:08.387911", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:09.011741", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:09.571625", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:10.148215", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:10.706373", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:11.281594", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:11.953592", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:12.544875", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:13.120050", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:13.664086", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:14.239449", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:14.847458", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:15.790408", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:15.926407", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-08T17:52:15.927926", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-08T17:52:15.930256", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-08T17:52:15.933308", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-08T17:52:15.936576", "lvl": "input", "msg": "tftp 0x4a000000 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-08T17:52:16.038238", "lvl": "target", "msg": "=> tftp 0x4a000000 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:52:16.040393", "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-08T17:52:16.045167", "lvl": "target", "msg": "tftp 0x4a000000 34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-08T17:52:16.076938", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-08T17:52:16.081332", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-08T17:52:16.083863", "lvl": "target", "msg": "Filename '34075/nbd-deploy-uz72iu7c/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-08T17:52:16.086255", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-08T17:52:17.422062", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-08T17:52:18.158041", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-08T17:52:18.266590", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-08T17:52:18.268670", "lvl": "target", "msg": "\t 923.8 KiB/s"}
- {"dt": "2023-12-08T17:52:18.270453", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-08T17:52:18.273105", "lvl": "target", "msg": "Bytes transferred = 2062770 (1f79b2 hex)"}
- {"dt": "2023-12-08T17:52:18.275528", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-08T17:52:18.376960", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-08T17:52:18.379018", "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:20)"}
- {"dt": "2023-12-08T17:52:18.396583", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-08T17:52:18.398609", "lvl": "input", "msg": "tftp 0x68000000 34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-08T17:52:18.501149", "lvl": "target", "msg": "=> tftp 0x68000000 34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:52:18.503390", "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:20)"}
- {"dt": "2023-12-08T17:52:18.540262", "lvl": "target", "msg": "tftp 0x68000000 34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-08T17:52:18.541574", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-08T17:52:18.543408", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-08T17:52:18.544951", "lvl": "target", "msg": "Filename '34075/nbd-deploy-uz72iu7c/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-08T17:52:18.546502", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-08T17:52:18.601268", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-08T17:52:18.602769", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-08T17:52:18.604203", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-08T17:52:18.605544", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-08T17:52:18.607542", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61974 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-08T17:52:18.710014", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61974 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-08T17:52:18.712336", "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:19)"}
- {"dt": "2023-12-08T17:52:18.731468", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61974 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-08T17:52:18.733049", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-08T17:52:18.835120", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-08T17:52:18.837471", "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-08T17:52:18.839614", "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:19)"}
- {"dt": "2023-12-08T17:52:18.843536", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-08T17:52:18.859991", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-08T17:52:19.006278", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-08T17:52:19.008391", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-08T17:52:19.010288", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff9b2 ... OK"}
- {"dt": "2023-12-08T17:52:19.012948", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-08T17:52:19.014831", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-08T17:52:19.015922", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-08T17:52:19.016462", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-08T17:52:19.016987", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-08T17:52:19.017506", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-08T17:52:19.050434", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-08T17:52:19.051098", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-08T17:52:19.052491", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-08T17:52:19.053413", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-08T17:52:19.054242", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-08T17:52:19.055202", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-08T17:52:19.056170", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-08T17:52:19.057095", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-08T17:52:19.058064", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-08T17:52:19.059003", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-08T17:52:19.060141", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-08T17:52:19.071765", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-08T17:52:19.072447", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:52:19.075484", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-08T17:52:19.076474", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-08T17:52:19.077370", "lvl": "target", "msg": ""}
- {"dt": "2023-12-08T17:52:19.078958", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-08T17:52:19.080085", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:31) [common]"}
- {"dt": "2023-12-08T17:52:19.081122", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "31.38", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-08T17:52:19.083122", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-08T17:52:19.083981", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:38) [common]"}
- {"dt": "2023-12-08T17:52:19.084708", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "37.86", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-08T17:52:19.085820", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-08T17:52:19.086568", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-08T17:52:19.087312", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:41) [common]"}
- {"dt": "2023-12-08T17:52:19.088032", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "41.06", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-08T17:52:19.089371", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-08T17:52:19.090165", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/initrd"}
- {"dt": "2023-12-08T17:52:19.092050", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/kernel"}
- {"dt": "2023-12-08T17:52:19.100762", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/dtb"}
- {"dt": "2023-12-08T17:52:19.102245", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34075/nbd-deploy-uz72iu7c/nbdroot"}
- {"dt": "2023-12-08T17:52:19.410284", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-08T17:52:19.411485", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-08T17:52:22.858778", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-08T17:52:22.895929", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-08T17:52:23.000142", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-08T17:52:23.001599", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.59", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-08T17:52:23.005784", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-08T17:52:23.009167", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-08T17:52:23.010864", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.012174", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.013169", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.014275", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.015380", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.016377", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.017275", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.018135", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.019072", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.020986", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.021964", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.022934", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.024025", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.025022", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.025930", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.026811", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.028554", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.029531", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.030492", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.031642", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.032614", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.033490", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.034455", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.035957", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.037152", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.038220", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.039940", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.040992", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.041886", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.042746", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.044582", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.046485", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.048415", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.050132", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.052015", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.052748", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.053293", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.053796", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.054284", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.054770", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.055303", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.055802", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.056290", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.056784", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.057279", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.057773", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.058257", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.058752", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.059268", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-08T17:52:23.060182", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-08T17:52:24.011815", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-08T17:52:24.014051", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-08T17:52:24.015806", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-08T17:52:24.118877", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-08T17:52:24.121108", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-08T17:52:24.122877", "lvl": "debug", "msg": "clean NBD port 61974"}
- {"dt": "2023-12-08T17:52:27.422266", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34075"}
- {"dt": "2023-12-08T17:52:27.511802", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34075"}
- {"dt": "2023-12-08T17:52:27.512891", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-08T17:52:27.513498", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
