- {"dt": "2023-12-07T05:53:04.985176", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-07T05:53:04.986466", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-07T05:53:04.987458", "lvl": "info", "msg": "Start time: 2023-12-07 05:53:04.987402+00:00 (UTC)"}
- {"dt": "2023-12-07T05:53:04.988621", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-07T05:53:05.214879", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-07T05:53:05.429897", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-07T05:53:05.643371", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-07T05:53:05.860359", "lvl": "info", "msg": "validate duration: 0.87"}
- {"dt": "2023-12-07T05:53:05.861189", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-07T05:53:05.862337", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:53:05.863003", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-07T05:53:05.863602", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-07T05:53:05.864847", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-07T05:53:05.865982", "lvl": "debug", "msg": "Set_port 61961"}
- {"dt": "2023-12-07T05:53:05.866877", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:53:05.867671", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-07T05:53:05.869549", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:53:05.870251", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:53:05.870822", "lvl": "debug", "msg": "total size: 2062696 (1MB)"}
- {"dt": "2023-12-07T05:53:05.871323", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:53:06.176265", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-07T05:53:06.371450", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-07T05:53:06.468298", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-07T05:53:06.474462", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-07T05:53:06.565512", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-07T05:53:06.570592", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-07T05:53:06.576849", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-07T05:53:06.661247", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-07T05:53:06.668400", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-07T05:53:06.673338", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-07T05:53:06.677889", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-07T05:53:06.683937", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-07T05:53:06.688765", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-07T05:53:06.764073", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-07T05:53:06.773124", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-07T05:53:06.778439", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-07T05:53:06.785152", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-07T05:53:06.790468", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-07T05:53:06.867136", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-07T05:53:06.872399", "lvl": "info", "msg": "1MB downloaded in 1.00s (1.96MB/s)"}
- {"dt": "2023-12-07T05:53:06.873920", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:53:06.874752", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.01", "extra": {"label": "initrd", "md5sum": "af5f9bf260d07220e1365276bca832e0", "sha256sum": "c5cebb4530e00a70bacfa18f74e19f9f43c58226085c9d7545d19b874f49e1d0", "sha512sum": "4d5880b46358312771b29c946ef119ef7e954a7fd7801f7c19841eb56ebe0fc4d1f1924217c84284960dbc85e70b2f881fb22cca8b49a2c58689c83586929cf5", "size": ! "2062696"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:53:06.876587", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:53:06.877605", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-07T05:53:06.878566", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-07T05:53:06.879863", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-07T05:53:06.880749", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/kernel/Image"}
- {"dt": "2023-12-07T05:53:06.881422", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-07T05:53:06.882157", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:53:08.096824", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-07T05:53:08.701491", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-07T05:53:10.377282", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-07T05:53:12.682348", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-07T05:53:13.374640", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-07T05:53:13.970093", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-07T05:53:14.557529", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-07T05:53:15.124349", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-07T05:53:15.644010", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-07T05:53:16.206114", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-07T05:53:16.718851", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-07T05:53:17.262159", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-07T05:53:17.778254", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-07T05:53:18.286542", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-07T05:53:18.835175", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-07T05:53:19.356039", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-07T05:53:19.892531", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-07T05:53:20.410967", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-07T05:53:20.912971", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-07T05:53:21.412081", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-07T05:53:21.900545", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-07T05:53:21.902350", "lvl": "info", "msg": "37MB downloaded in 15.02s (2.49MB/s)"}
- {"dt": "2023-12-07T05:53:21.903748", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:15) [common]"}
- {"dt": "2023-12-07T05:53:21.904650", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "15.03", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:53:21.906469", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:15) [common]"}
- {"dt": "2023-12-07T05:53:21.907402", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:44) [common]"}
- {"dt": "2023-12-07T05:53:21.908329", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:44) [common]"}
- {"dt": "2023-12-07T05:53:21.909563", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:53:21.910332", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:53:21.910972", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-07T05:53:21.911591", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-07T05:53:22.229125", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-07T05:53:22.331440", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-07T05:53:22.333998", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-07T05:53:22.335453", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-12-07T05:53:22.337022", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:53:22.337750", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.43", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:53:22.339266", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:53:22.340613", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:44) [common]"}
- {"dt": "2023-12-07T05:53:22.341562", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:44) [common]"}
- {"dt": "2023-12-07T05:53:22.342662", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29508/1/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-07T05:53:22.343355", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:53:22.343908", "lvl": "debug", "msg": "total size: 453842336 (432MB)"}
- {"dt": "2023-12-07T05:53:22.344533", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-07T05:53:22.666647", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-07T05:53:29.011695", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-07T05:53:33.345644", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-07T05:53:38.477443", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-07T05:53:42.788340", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-07T05:53:47.857250", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-07T05:53:52.870113", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-07T05:53:58.818330", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-07T05:54:06.136835", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-07T05:54:12.983422", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-07T05:54:18.014465", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-07T05:54:23.612331", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-07T05:54:28.661999", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-07T05:54:33.209642", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-07T05:54:38.175103", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-07T05:54:42.745230", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-07T05:54:47.379339", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-07T05:54:52.088449", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-07T05:54:56.767299", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-07T05:55:01.548286", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-07T05:55:10.599207", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-07T05:55:13.846304", "lvl": "info", "msg": "432MB downloaded in 111.50s (3.88MB/s)"}
- {"dt": "2023-12-07T05:55:13.848311", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:52) [common]"}
- {"dt": "2023-12-07T05:55:13.849134", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "111.51", "extra": {"label": "nbdroot", "md5sum": "6ddb2e5f438e27636c3cb4da7c3a2af0", "sha256sum": "9c696e3f1c1d88ac20f6bfc28cb65ed79fd98901081efe18a783074bb92ce9c3", "sha512sum": "29bd8ed9eb7eae409b57ac55c6fc26435296c111c7518b104b1ad4e7ab00f1e2c1d905f4674d2ce2db6584c484260c82f0ed6a46e27803e6f43151cd1967f117", "size": ! "453842336"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:13.850886", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:52) [common]"}
- {"dt": "2023-12-07T05:55:13.851835", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.853600", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm"}
- {"dt": "2023-12-07T05:55:13.854962", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin"}
- {"dt": "2023-12-07T05:55:13.856168", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/tests"}
- {"dt": "2023-12-07T05:55:13.857398", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/results"}
- {"dt": "2023-12-07T05:55:13.858690", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-add-keys"}
- {"dt": "2023-12-07T05:55:13.860382", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-add-sources"}
- {"dt": "2023-12-07T05:55:13.861849", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-background-process-start"}
- {"dt": "2023-12-07T05:55:13.863339", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-background-process-stop"}
- {"dt": "2023-12-07T05:55:13.866503", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-common-functions"}
- {"dt": "2023-12-07T05:55:13.868056", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-echo-ipv4"}
- {"dt": "2023-12-07T05:55:13.869782", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-install-packages"}
- {"dt": "2023-12-07T05:55:13.871539", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-installed-packages"}
- {"dt": "2023-12-07T05:55:13.873580", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-os-build"}
- {"dt": "2023-12-07T05:55:13.875317", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-probe-channel"}
- {"dt": "2023-12-07T05:55:13.877153", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-probe-ip"}
- {"dt": "2023-12-07T05:55:13.878951", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-target-ip"}
- {"dt": "2023-12-07T05:55:13.880780", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-target-mac"}
- {"dt": "2023-12-07T05:55:13.882386", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-target-storage"}
- {"dt": "2023-12-07T05:55:13.884002", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-case"}
- {"dt": "2023-12-07T05:55:13.885605", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-event"}
- {"dt": "2023-12-07T05:55:13.887124", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-feedback"}
- {"dt": "2023-12-07T05:55:13.888805", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-raise"}
- {"dt": "2023-12-07T05:55:13.890166", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-reference"}
- {"dt": "2023-12-07T05:55:13.891528", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-runner"}
- {"dt": "2023-12-07T05:55:13.893179", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-set"}
- {"dt": "2023-12-07T05:55:13.894765", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-test-shell"}
- {"dt": "2023-12-07T05:55:13.896592", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-07T05:55:13.898569", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-07T05:55:13.900117", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/environment"}
- {"dt": "2023-12-07T05:55:13.901730", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-07T05:55:13.902540", "lvl": "debug", "msg": "- LAVA_JOB_ID=34040"}
- {"dt": "2023-12-07T05:55:13.903269", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-07T05:55:13.904753", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.908011", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:13.909008", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.909815", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-07T05:55:13.910916", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:13.911879", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.912899", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-07T05:55:13.914016", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:13.915236", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.916292", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-07T05:55:13.917567", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:52) [common]"}
- {"dt": "2023-12-07T05:55:13.918479", "lvl": "debug", "msg": "Using /lava-34040 at stage 0"}
- {"dt": "2023-12-07T05:55:13.919725", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:13.920750", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/0/tests/0_network-veth'"}
- {"dt": "2023-12-07T05:55:14.539753", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-07T05:55:14.544338", "lvl": "debug", "msg": "uuid=34040_1.5.4.1 testdef=None"}
- {"dt": "2023-12-07T05:55:14.545482", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:14.546267", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "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-07T05:55:14.547767", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:14.552958", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:14.553667", "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": "34040_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:14.555254", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:14.562855", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:14.563664", "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": "34040_1.5.4.1", "uuid": "34040_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:14.565356", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:14.571759", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/0/tests/0_network-veth test_uuid 34040_1.5.4.1"}
- {"dt": "2023-12-07T05:55:14.573487", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:14.574215", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:14.578302", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:14.579101", "lvl": "debug", "msg": "Using /lava-34040 at stage 1"}
- {"dt": "2023-12-07T05:55:14.580151", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:14.581057", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-07T05:55:15.182558", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/1_ci-busybox"}
- {"dt": "2023-12-07T05:55:15.195461", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-07T05:55:15.199668", "lvl": "debug", "msg": "uuid=34040_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-07T05:55:15.201141", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:15.201949", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.62", "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-07T05:55:15.203413", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:15.210296", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.211142", "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": "34040_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.212963", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:15.221999", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.222730", "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": "34040_1.5.4.5", "uuid": "34040_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.224369", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:15.232844", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/1_ci-busybox test_uuid 34040_1.5.4.5"}
- {"dt": "2023-12-07T05:55:15.234715", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.235519", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.237296", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:51) [common]"}
- {"dt": "2023-12-07T05:55:15.238184", "lvl": "debug", "msg": "Using /lava-34040 at stage 1"}
- {"dt": "2023-12-07T05:55:15.239401", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:15.240332", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-07T05:55:15.834983", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-07T05:55:15.845778", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-07T05:55:15.848840", "lvl": "debug", "msg": "uuid=34040_1.5.4.9 testdef=None"}
- {"dt": "2023-12-07T05:55:15.849997", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:15.850779", "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-07T05:55:15.852368", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-12-07T05:55:15.857954", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.858699", "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": "34040_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.860357", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-12-07T05:55:15.867779", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.868612", "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": "34040_1.5.4.9", "uuid": "34040_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.870286", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-12-07T05:55:15.877811", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/2_ci-smoke-tests-basic test_uuid 34040_1.5.4.9"}
- {"dt": "2023-12-07T05:55:15.880687", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:15.881479", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:15.883123", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:50) [common]"}
- {"dt": "2023-12-07T05:55:15.883874", "lvl": "debug", "msg": "Using /lava-34040 at stage 1"}
- {"dt": "2023-12-07T05:55:15.885042", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:15.885840", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-07T05:55:16.472962", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/3_ci-service-check"}
- {"dt": "2023-12-07T05:55:16.487005", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-07T05:55:16.491519", "lvl": "debug", "msg": "uuid=34040_1.5.4.13 testdef=None"}
- {"dt": "2023-12-07T05:55:16.493397", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:16.494421", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.61", "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-07T05:55:16.496369", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:16.503546", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:16.504509", "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": "34040_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:16.506539", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:16.515228", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:16.515987", "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": "34040_1.5.4.13", "uuid": "34040_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:16.517659", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:16.524995", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1/tests/3_ci-service-check test_uuid 34040_1.5.4.13"}
- {"dt": "2023-12-07T05:55:16.525750", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-07T05:55:16.526402", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-07T05:55:16.527970", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:16.528796", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:16.531359", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:16.532727", "lvl": "debug", "msg": "Using /lava-34040 at stage 2"}
- {"dt": "2023-12-07T05:55:16.533911", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:16.534640", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/2/tests/4_screenshooter'"}
- {"dt": "2023-12-07T05:55:17.117193", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-07T05:55:17.122598", "lvl": "debug", "msg": "uuid=34040_1.5.4.17 testdef=None"}
- {"dt": "2023-12-07T05:55:17.123871", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:17.125480", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "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-07T05:55:17.127151", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:17.132169", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.132964", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34040_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.134599", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:17.142322", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.143111", "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": "34040_1.5.4.17", "uuid": "34040_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.144801", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:17.151298", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/2/tests/4_screenshooter test_uuid 34040_1.5.4.17"}
- {"dt": "2023-12-07T05:55:17.153102", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.153886", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.155588", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:49) [common]"}
- {"dt": "2023-12-07T05:55:17.156460", "lvl": "debug", "msg": "Using /lava-34040 at stage 3"}
- {"dt": "2023-12-07T05:55:17.157611", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-07T05:55:17.158501", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-07T05:55:17.788844", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-07T05:55:17.801552", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-07T05:55:17.805958", "lvl": "debug", "msg": "uuid=34040_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-07T05:55:17.807204", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:17.807943", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.65", "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-07T05:55:17.809483", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:55:17.815205", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.815940", "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": "34040_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.817585", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:55:17.825599", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.826345", "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": "34040_1.5.4.21", "uuid": "34040_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.827948", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:55:17.835806", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/3/tests/5_ci-dumpjournal test_uuid 34040_1.5.4.21"}
- {"dt": "2023-12-07T05:55:17.837532", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:17.838278", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/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": "34040_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:17.839689", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-07T05:55:17.840293", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/0 for stage 0"}
- {"dt": "2023-12-07T05:55:17.841238", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-07T05:55:17.842064", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/1 for stage 1"}
- {"dt": "2023-12-07T05:55:17.842991", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-07T05:55:17.843645", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-07T05:55:17.844144", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-07T05:55:17.844889", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/2 for stage 2"}
- {"dt": "2023-12-07T05:55:17.845840", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-07T05:55:17.846625", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34040/lava-overlay-z3w02ufm/lava-34040/3 for stage 3"}
- {"dt": "2023-12-07T05:55:17.847566", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-07T05:55:17.848706", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-07T05:55:17.849598", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:48) [common]"}
- {"dt": "2023-12-07T05:55:18.697444", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:55:18.698490", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:55:18.699906", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.700996", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:55:18.701934", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:55:18.702864", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:55:18.703825", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.704783", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.705693", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:47) [common]"}
- {"dt": "2023-12-07T05:55:18.706435", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-07T05:55:18.707259", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61961, NBD-ROOT: 34040/nbd-deploy-mzkknwl7/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:55:18.711213", "lvl": "debug", "msg": "nbd-server 61961 /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-07T05:55:18.739185", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-07T05:55:18.741151", "lvl": "debug", "msg": "output: ** (process:12706): WARNING **: 05:55:18.733: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-07T05:55:18.741931", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-07T05:55:18.742571", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-07T05:55:18.743630", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.744861", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:13) [common]"}
- {"dt": "2023-12-07T05:55:18.745921", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:55:18.746927", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:55:18.747852", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.750542", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:55:18.752017", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-07T05:55:18.752903", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:55:18.753585", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-07T05:55:18.754218", "lvl": "debug", "msg": "- {DTB}: 34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:55:18.754813", "lvl": "debug", "msg": "- {INITRD}: 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:55:18.755455", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-07T05:55:18.757161", "lvl": "debug", "msg": "- {KERNEL}: 34040/nbd-deploy-mzkknwl7/kernel/Image"}
- {"dt": "2023-12-07T05:55:18.757924", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-07T05:55:18.758641", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-07T05:55:18.759379", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61961"}
- {"dt": "2023-12-07T05:55:18.760018", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-07T05:55:18.760718", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-07T05:55:18.761286", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-07T05:55:18.761828", "lvl": "debug", "msg": "- {RAMDISK}: 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:55:18.762356", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-07T05:55:18.762874", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-07T05:55:18.763446", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-07T05:55:18.764005", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-07T05:55:18.767513", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-07T05:55:18.768146", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-07T05:55:18.768881", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-07T05:55:18.769475", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:18.770013", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:18.770626", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-07T05:55:18.771208", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:55:18.771755", "lvl": "info", "msg": "- tftp 0x68080000 34040/nbd-deploy-mzkknwl7/kernel/Image"}
- {"dt": "2023-12-07T05:55:18.772438", "lvl": "info", "msg": "- tftp 0x4a000000 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:55:18.773211", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:55:18.773715", "lvl": "info", "msg": "- tftp 0x68000000 34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:55:18.774227", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61961 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-07T05:55:18.774881", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:55:18.775653", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-07T05:55:18.776511", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.03", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:18.778240", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-07T05:55:18.778975", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-07T05:55:22.372935", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-07T05:55:22.375249", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:04) [common]"}
- {"dt": "2023-12-07T05:55:22.376367", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:56) [common]"}
- {"dt": "2023-12-07T05:55:22.377271", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:56) [common]"}
- {"dt": "2023-12-07T05:55:22.378312", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:56) [common]"}
- {"dt": "2023-12-07T05:55:22.379715", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-07T05:55:25.927198", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:55:26.998922", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:55:27.038651", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-07T05:55:27.141223", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:55:27.142760", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.76", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:55:27.145283", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-07T05:55:27.146748", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-07T05:55:27.147844", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-07T05:55:27.150055", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-07T05:55:27.153703", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-07T05:55:27.155207", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-07T05:55:27.156572", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-07T05:55:27.214325", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Init\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.215110", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.263592", "lvl": "target", "msg": "NOTICE:  BL\uFFFD\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.264664", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.346159", "lvl": "target", "msg": "NOTICE:  BL2: Board is Start\0NOTICE:  BL2: R-Car Gen3 Initial Pro\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.347015", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.388917", "lvl": "target", "msg": "NOTIC\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.389975", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.434685", "lvl": "target", "msg": "NOTICE:  BL2: Board is St\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.435466", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.483193", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.483964", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.486584", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:55:27.534857", "lvl": "target", "msg": "NOTICE:  BL2: B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.535662", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.536614", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:55:27.589109", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80M\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.589897", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.590431", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:55:27.592358", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:55:27.652901", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.653760", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.654961", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:55:27.655646", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:55:27.656145", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-07T05:55:27.656958", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-07T05:55:27.657522", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-07T05:55:27.721629", "lvl": "target", "msg": "\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-07T05:55:27.722425", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-07T05:55:27.722961", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-07T05:55:27.723575", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-07T05:55:27.724134", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-07T05:55:27.724730", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-07T05:55:27.725201", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-07T05:55:27.725875", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-07T05:55:27.766868", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-07T05:55:27.767723", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-07T05:55:27.768728", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-07T05:55:27.769406", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-07T05:55:27.769925", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:27.770388", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:27.770924", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:27.771419", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:27.771888", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-07T05:55:27.772425", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-07T05:55:27.773065", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-07T05:55:27.810273", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-07T05:55:27.811115", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-07T05:55:27.812317", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-07T05:55:27.813032", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-07T05:55:27.813534", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-07T05:55:27.814067", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-07T05:55:27.814694", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-07T05:55:27.849034", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-07T05:55:27.849820", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-07T05:55:27.852181", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-07T05:55:28.181683", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:55:28.182466", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:55:28.182994", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-07T05:55:28.183466", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:55:28.183927", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-07T05:55:28.184916", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-07T05:55:28.375991", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-07T05:55:28.406553", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-07T05:55:28.408127", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-07T05:55:28.409409", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:28.410477", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:28.411129", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-07T05:55:28.411625", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:55:28.453550", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-07T05:55:28.620958", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-07T05:55:28.622413", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-07T05:55:28.623715", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-07T05:55:28.626565", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-07T05:55:28.671314", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-07T05:55:28.673109", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-07T05:55:28.724649", "lvl": "target", "msg": "Hit any key to stop autoboot:"}
- {"dt": "2023-12-07T05:55:28.725772", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-07T05:55:28.727386", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:50) [common]"}
- {"dt": "2023-12-07T05:55:28.728535", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-07T05:55:28.729580", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:50)"}
- {"dt": "2023-12-07T05:55:28.734809", "lvl": "target", "msg": "  3 \b\b\b 0 "}
- {"dt": "2023-12-07T05:55:28.736475", "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-07T05:55:28.737203", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-07T05:55:28.838698", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-07T05:55:28.839743", "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-07T05:55:28.847126", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-07T05:55:28.848303", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-07T05:55:28.949962", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:28.951410", "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-07T05:55:28.958307", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:28.959660", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-07T05:55:29.061895", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:29.065148", "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-07T05:55:29.071280", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-07T05:55:29.073458", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-07T05:55:29.176370", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-07T05:55:29.177865", "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-07T05:55:29.182911", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-07T05:55:32.334654", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2023-12-07T05:55:32.335737", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-07T05:55:32.590531", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-07T05:55:33.085281", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-07T05:55:33.372616", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1032 ms)"}
- {"dt": "2023-12-07T05:55:33.374381", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-07T05:55:33.476859", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:55:33.478994", "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-07T05:55:33.484399", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-07T05:55:33.485531", "lvl": "input", "msg": "tftp 0x68080000 34040/nbd-deploy-mzkknwl7/kernel/Image\n"}
- {"dt": "2023-12-07T05:55:33.587088", "lvl": "target", "msg": "=> tftp 0x68080000 34040/nbd-deploy-mzkknwl7/kernel/Image"}
- {"dt": "2023-12-07T05:55:33.587957", "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-07T05:55:33.596757", "lvl": "target", "msg": "tftp 0x68080000 34040/nbd-deploy-mzkknwl7/kernel/Image"}
- {"dt": "2023-12-07T05:55:33.621020", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:55:33.621774", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:55:33.623534", "lvl": "target", "msg": "Filename '34040/nbd-deploy-mzkknwl7/kernel/Image'."}
- {"dt": "2023-12-07T05:55:33.624316", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-07T05:55:34.166348", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-07T05:55:34.661625", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:35.124836", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:35.699625", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:36.913831", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:38.176484", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:38.847431", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:39.407038", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:39.966863", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:40.558375", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:41.102707", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:41.693474", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:42.284870", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:42.829146", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:43.388285", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:44.011333", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:44.683318", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:45.242893", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:45.817845", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:46.361598", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:46.937597", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:47.561133", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:48.152865", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:48.729191", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:49.399693", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:50.183028", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:50.758585", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:51.446448", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:52.086304", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:52.805915", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:53.412659", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:54.131987", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:54.707717", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:55.267152", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:55.842712", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:56.417891", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:57.009670", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:57.585401", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:58.160771", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:58.752068", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:59.311806", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:55:59.359776", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-07T05:55:59.361918", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-07T05:55:59.363409", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:55:59.376572", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-07T05:55:59.379431", "lvl": "input", "msg": "tftp 0x4a000000 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-07T05:55:59.482189", "lvl": "target", "msg": "=> tftp 0x4a000000 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:55:59.484310", "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-07T05:55:59.521194", "lvl": "target", "msg": "tftp 0x4a000000 34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-07T05:55:59.521926", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:55:59.522989", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:55:59.523723", "lvl": "target", "msg": "Filename '34040/nbd-deploy-mzkknwl7/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-07T05:55:59.525178", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-07T05:56:00.131051", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-07T05:56:00.738510", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-07T05:56:00.842424", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-07T05:56:00.843876", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-07T05:56:00.845390", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:56:00.846787", "lvl": "target", "msg": "Bytes transferred = 2062696 (1f7968 hex)"}
- {"dt": "2023-12-07T05:56:00.849463", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-07T05:56:00.951922", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:56:00.954159", "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:18)"}
- {"dt": "2023-12-07T05:56:00.972512", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-07T05:56:00.973951", "lvl": "input", "msg": "tftp 0x68000000 34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-07T05:56:01.076386", "lvl": "target", "msg": "=> tftp 0x68000000 34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:56:01.078611", "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:18)"}
- {"dt": "2023-12-07T05:56:01.083588", "lvl": "target", "msg": "tftp 0x68000000 34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-07T05:56:01.115387", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-07T05:56:01.116575", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-07T05:56:01.118070", "lvl": "target", "msg": "Filename '34040/nbd-deploy-mzkknwl7/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-07T05:56:01.119463", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-07T05:56:01.176062", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-07T05:56:01.178039", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-12-07T05:56:01.179770", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-07T05:56:01.181576", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-07T05:56:01.183789", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61961 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-07T05:56:01.285321", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61961 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-07T05:56:01.286534", "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:17)"}
- {"dt": "2023-12-07T05:56:01.306093", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61961 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-07T05:56:01.307722", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-07T05:56:01.409648", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:56:01.410741", "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-07T05:56:01.411497", "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:17)"}
- {"dt": "2023-12-07T05:56:01.418181", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-07T05:56:01.433829", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-07T05:56:01.580315", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-07T05:56:01.582037", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-07T05:56:01.583504", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff968 ... OK"}
- {"dt": "2023-12-07T05:56:01.585873", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-07T05:56:01.587469", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-07T05:56:01.588792", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-07T05:56:01.589255", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-07T05:56:01.589694", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-07T05:56:01.590126", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-07T05:56:01.590555", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-07T05:56:01.625184", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-07T05:56:01.626647", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-07T05:56:01.627560", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-07T05:56:01.628426", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-07T05:56:01.629203", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-07T05:56:01.629933", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-07T05:56:01.630654", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-07T05:56:01.631369", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-07T05:56:01.632148", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-07T05:56:01.632967", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-07T05:56:01.633853", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-07T05:56:01.634609", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:56:01.646504", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-07T05:56:01.649548", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-07T05:56:01.650576", "lvl": "target", "msg": ""}
- {"dt": "2023-12-07T05:56:01.652103", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:56:01.653264", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:33) [common]"}
- {"dt": "2023-12-07T05:56:01.654254", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "32.92", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:56:01.656325", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:56:01.657366", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:39) [common]"}
- {"dt": "2023-12-07T05:56:01.658549", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "39.28", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:56:01.660514", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-07T05:56:01.661175", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-07T05:56:01.661689", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:43) [common]"}
- {"dt": "2023-12-07T05:56:01.662212", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "42.92", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-07T05:56:01.663386", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-07T05:56:01.664130", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/initrd"}
- {"dt": "2023-12-07T05:56:01.666009", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/kernel"}
- {"dt": "2023-12-07T05:56:01.674843", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/dtb"}
- {"dt": "2023-12-07T05:56:01.676392", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34040/nbd-deploy-mzkknwl7/nbdroot"}
- {"dt": "2023-12-07T05:56:01.996136", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-07T05:56:01.997388", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-07T05:56:05.788680", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-07T05:56:05.825238", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-07T05:56:05.928161", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-07T05:56:05.929315", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.93", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-07T05:56:05.931448", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-07T05:56:05.933745", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-07T05:56:05.935243", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.936402", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.937416", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.938285", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.939131", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.939995", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.940934", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.941823", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.942696", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.943548", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.944425", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.945286", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.946167", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.947144", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.948090", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.949024", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.949881", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.950718", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.951557", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.952421", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.953284", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.954116", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.954946", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.955774", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.956679", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.957526", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.958357", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.959243", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.960106", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.961091", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.961950", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.962749", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.963440", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.963959", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.965002", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.965874", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.966717", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.967557", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.968427", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.969272", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.970115", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.970936", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.971771", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.972696", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.973524", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.974345", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.975234", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.976304", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.977206", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-07T05:56:05.978709", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-07T05:56:06.936868", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-07T05:56:06.939020", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-07T05:56:06.940581", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-07T05:56:07.043601", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-07T05:56:07.045281", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-07T05:56:07.046429", "lvl": "debug", "msg": "clean NBD port 61961"}
- {"dt": "2023-12-07T05:56:10.420989", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34040"}
- {"dt": "2023-12-07T05:56:10.514564", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34040"}
- {"dt": "2023-12-07T05:56:10.515616", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-07T05:56:10.516266", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
