- {"dt": "2023-03-09T05:53:52.632598", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-09T05:53:52.633869", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-09T05:53:52.634924", "lvl": "info", "msg": "Start time: 2023-03-09 05:53:52.634825+00:00 (UTC)"}
- {"dt": "2023-03-09T05:53:52.636036", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-03-09T05:53:54.464598", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-03-09T05:53:54.674640", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb exists"}
- {"dt": "2023-03-09T05:53:54.885784", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-03-09T05:53:55.116008", "lvl": "info", "msg": "validate duration: 2.48"}
- {"dt": "2023-03-09T05:53:55.116837", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-09T05:53:55.118012", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T05:53:55.118690", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-03-09T05:53:55.119385", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-03-09T05:53:55.119983", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-03-09T05:53:55.120998", "lvl": "debug", "msg": "Set_port 61999"}
- {"dt": "2023-03-09T05:53:55.121869", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T05:53:55.122735", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T05:53:55.124050", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:53:55.124765", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:53:55.125405", "lvl": "debug", "msg": "total size: 2057818 (1MB)"}
- {"dt": "2023-03-09T05:53:55.125983", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T05:53:55.526565", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-03-09T05:53:55.721287", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-03-09T05:53:55.818285", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-03-09T05:53:55.832709", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-03-09T05:53:55.925821", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-03-09T05:53:55.932315", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-03-09T05:53:56.029754", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-03-09T05:53:56.119985", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-03-09T05:53:56.213645", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-03-09T05:53:56.223265", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-03-09T05:53:56.312224", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-03-09T05:53:56.323940", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-03-09T05:53:56.417422", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-03-09T05:53:56.504927", "lvl": "debug", "msg": "progress  70% (1MB)"}
- {"dt": "2023-03-09T05:53:56.520408", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-03-09T05:53:56.613493", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-03-09T05:53:56.699689", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-03-09T05:53:56.718260", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-03-09T05:53:56.803983", "lvl": "debug", "msg": "progress  97% (1MB)"}
- {"dt": "2023-03-09T05:53:56.815933", "lvl": "info", "msg": "1MB downloaded in 1.69s (1.16MB/s)"}
- {"dt": "2023-03-09T05:53:56.817427", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2023-03-09T05:53:56.818273", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.69", "extra": {"label": "initrd", "md5sum": "28ac0b000066ef9accb6e27c5e4f1541", "sha256sum": "61dbdb553c35c6bcb3933f2b1a4cc3c2991e688fd4a8d56d72673c6a531c7285", "sha512sum": "45e75163a9620ec32113322968d0631d0ddeba121caf320c3423441be5b1b9fe5311a2d4ca9116da5d7032daa62689f61f1578a72d159aacdb02e34cf342673b", "size": ! "2057818"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:53:56.820295", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:02) [common]"}
- {"dt": "2023-03-09T05:53:56.821273", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:58) [common]"}
- {"dt": "2023-03-09T05:53:56.822240", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:58) [common]"}
- {"dt": "2023-03-09T05:53:56.823655", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image"}
- {"dt": "2023-03-09T05:53:56.824497", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/kernel/Image"}
- {"dt": "2023-03-09T05:53:56.825205", "lvl": "debug", "msg": "total size: 39076352 (37MB)"}
- {"dt": "2023-03-09T05:53:56.825909", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T05:53:57.226507", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-09T05:53:58.415059", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-09T05:53:59.493130", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-03-09T05:54:00.475813", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-03-09T05:54:01.443078", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-03-09T05:54:02.314717", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-03-09T05:54:03.054979", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-03-09T05:54:03.696163", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-03-09T05:54:04.279714", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-03-09T05:54:04.806687", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-03-09T05:54:05.312908", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-03-09T05:54:05.900216", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-03-09T05:54:06.437938", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-03-09T05:54:06.969159", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-03-09T05:54:07.455347", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-03-09T05:54:07.911657", "lvl": "debug", "msg": "progress  75% (27MB)"}
- {"dt": "2023-03-09T05:54:08.391991", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-03-09T05:54:08.848225", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-03-09T05:54:09.300092", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-03-09T05:54:09.741754", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-03-09T05:54:10.191184", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-03-09T05:54:10.194550", "lvl": "info", "msg": "37MB downloaded in 13.37s (2.79MB/s)"}
- {"dt": "2023-03-09T05:54:10.197052", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:13) [common]"}
- {"dt": "2023-03-09T05:54:10.198576", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "13.37", "extra": {"label": "kernel", "md5sum": "b7ad3f8a8c99f081f6b3a30284fcee3d", "sha256sum": "9192b0042c196dbbc05b4e1ed43cba3d335bb23a9fd5d1c31ba403d944a8b9c7", "sha512sum": "8095cb217fd581f94246357bca8803ee86c04c00798a44e6b04a99774a49b85f8c05273bb46c63a6441ddceec93fda3570883a32b48191aa80d9655bdf75d467", "size": ! "39076352"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:54:10.200635", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:13) [common]"}
- {"dt": "2023-03-09T05:54:10.201499", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:45) [common]"}
- {"dt": "2023-03-09T05:54:10.202361", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:45) [common]"}
- {"dt": "2023-03-09T05:54:10.203595", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:54:10.204345", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:54:10.204969", "lvl": "debug", "msg": "total size: 88958 (0MB)"}
- {"dt": "2023-03-09T05:54:10.205580", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T05:54:10.501437", "lvl": "debug", "msg": "progress  36% (0MB)"}
- {"dt": "2023-03-09T05:54:10.597632", "lvl": "debug", "msg": "progress  73% (0MB)"}
- {"dt": "2023-03-09T05:54:10.604524", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-03-09T05:54:10.609485", "lvl": "info", "msg": "0MB downloaded in 0.40s (0.21MB/s)"}
- {"dt": "2023-03-09T05:54:10.611810", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:54:10.612498", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.41", "extra": {"label": "dtb", "md5sum": "40d1b427f4f7f206d2f3b2d5730151f2", "sha256sum": "85ae8f72927f8de5e0844d4fb47fef48998e0ffbffb5c02391ce4b52c95290e0", "sha512sum": "eb7e817faa08ae2a831fe53ff0a4a4957b5b5e1ce3a761481c568b7573ef7b9573d2925b7160d0126f6d90dfb1b4007238eb03088c5875453f3ba13b6789bbeb", "size": ! "88958"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:54:10.614013", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:54:10.614958", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:45) [common]"}
- {"dt": "2023-03-09T05:54:10.615797", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:45) [common]"}
- {"dt": "2023-03-09T05:54:10.616937", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-03-09T05:54:10.617646", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T05:54:10.618231", "lvl": "debug", "msg": "total size: 486378676 (463MB)"}
- {"dt": "2023-03-09T05:54:10.618797", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-09T05:54:11.024702", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-09T05:54:18.281915", "lvl": "debug", "msg": "progress   5% (23MB)"}
- {"dt": "2023-03-09T05:54:22.913955", "lvl": "debug", "msg": "progress  10% (46MB)"}
- {"dt": "2023-03-09T05:54:28.043002", "lvl": "debug", "msg": "progress  15% (69MB)"}
- {"dt": "2023-03-09T05:54:32.360908", "lvl": "debug", "msg": "progress  20% (92MB)"}
- {"dt": "2023-03-09T05:54:37.301332", "lvl": "debug", "msg": "progress  25% (115MB)"}
- {"dt": "2023-03-09T05:54:42.384811", "lvl": "debug", "msg": "progress  30% (139MB)"}
- {"dt": "2023-03-09T05:54:49.101723", "lvl": "debug", "msg": "progress  35% (162MB)"}
- {"dt": "2023-03-09T05:54:55.944584", "lvl": "debug", "msg": "progress  40% (185MB)"}
- {"dt": "2023-03-09T05:55:01.575052", "lvl": "debug", "msg": "progress  45% (208MB)"}
- {"dt": "2023-03-09T05:55:08.740989", "lvl": "debug", "msg": "progress  50% (231MB)"}
- {"dt": "2023-03-09T05:55:14.534182", "lvl": "debug", "msg": "progress  55% (255MB)"}
- {"dt": "2023-03-09T05:55:21.162743", "lvl": "debug", "msg": "progress  60% (278MB)"}
- {"dt": "2023-03-09T05:55:27.036389", "lvl": "debug", "msg": "progress  65% (301MB)"}
- {"dt": "2023-03-09T05:55:32.457293", "lvl": "debug", "msg": "progress  70% (324MB)"}
- {"dt": "2023-03-09T05:55:36.549179", "lvl": "debug", "msg": "progress  75% (347MB)"}
- {"dt": "2023-03-09T05:55:40.798974", "lvl": "debug", "msg": "progress  80% (371MB)"}
- {"dt": "2023-03-09T05:55:45.048899", "lvl": "debug", "msg": "progress  85% (394MB)"}
- {"dt": "2023-03-09T05:55:49.423694", "lvl": "debug", "msg": "progress  90% (417MB)"}
- {"dt": "2023-03-09T05:55:54.000119", "lvl": "debug", "msg": "progress  95% (440MB)"}
- {"dt": "2023-03-09T05:56:02.635899", "lvl": "debug", "msg": "progress 100% (463MB)"}
- {"dt": "2023-03-09T05:56:05.760010", "lvl": "info", "msg": "463MB downloaded in 115.14s (4.03MB/s)"}
- {"dt": "2023-03-09T05:56:05.762142", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:55) [common]"}
- {"dt": "2023-03-09T05:56:05.763034", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "115.15", "extra": {"label": "nbdroot", "md5sum": "835b516ffbbad322c4bf1e73cb0f2aac", "sha256sum": "87a02b3a62db059a6ad8926d47ee0379756c469d30358ab3f41728a116037658", "sha512sum": "c5e84b43e6e0bfa4db68dbdff03cf757bdfa606175b5fb463634e61995dc13fff8bf2bb9c302341b9a133ae79450d6b7af29feadfeff58a46d4768ebf63e5c0a", "size": ! "486378676"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:05.764859", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:55) [common]"}
- {"dt": "2023-03-09T05:56:05.765990", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.768424", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp"}
- {"dt": "2023-03-09T05:56:05.770301", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin"}
- {"dt": "2023-03-09T05:56:05.771838", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/tests"}
- {"dt": "2023-03-09T05:56:05.773248", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/results"}
- {"dt": "2023-03-09T05:56:05.774624", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-add-keys"}
- {"dt": "2023-03-09T05:56:05.776089", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-add-sources"}
- {"dt": "2023-03-09T05:56:05.777340", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-background-process-start"}
- {"dt": "2023-03-09T05:56:05.778590", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-background-process-stop"}
- {"dt": "2023-03-09T05:56:05.779942", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-common-functions"}
- {"dt": "2023-03-09T05:56:05.781148", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-echo-ipv4"}
- {"dt": "2023-03-09T05:56:05.782332", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-install-packages"}
- {"dt": "2023-03-09T05:56:05.783619", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-installed-packages"}
- {"dt": "2023-03-09T05:56:05.784819", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-os-build"}
- {"dt": "2023-03-09T05:56:05.785987", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-probe-channel"}
- {"dt": "2023-03-09T05:56:05.787245", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-probe-ip"}
- {"dt": "2023-03-09T05:56:05.788465", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-target-ip"}
- {"dt": "2023-03-09T05:56:05.789652", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-target-mac"}
- {"dt": "2023-03-09T05:56:05.790843", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-target-storage"}
- {"dt": "2023-03-09T05:56:05.792151", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-case"}
- {"dt": "2023-03-09T05:56:05.793341", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-event"}
- {"dt": "2023-03-09T05:56:05.794548", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-feedback"}
- {"dt": "2023-03-09T05:56:05.795839", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-raise"}
- {"dt": "2023-03-09T05:56:05.797094", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-reference"}
- {"dt": "2023-03-09T05:56:05.798297", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-runner"}
- {"dt": "2023-03-09T05:56:05.799583", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-set"}
- {"dt": "2023-03-09T05:56:05.800820", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-test-shell"}
- {"dt": "2023-03-09T05:56:05.802055", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-09T05:56:05.803523", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-09T05:56:05.804779", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/environment"}
- {"dt": "2023-03-09T05:56:05.805835", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-09T05:56:05.806471", "lvl": "debug", "msg": "- LAVA_JOB_ID=32191"}
- {"dt": "2023-03-09T05:56:05.807071", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-03-09T05:56:05.808015", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.810241", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:05.811139", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.811697", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-09T05:56:05.812267", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:05.812940", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.813431", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-09T05:56:05.813982", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:05.814645", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.815343", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-09T05:56:05.816140", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:49) [common]"}
- {"dt": "2023-03-09T05:56:05.816770", "lvl": "debug", "msg": "Using /lava-32191 at stage 0"}
- {"dt": "2023-03-09T05:56:05.817605", "lvl": "info", "msg": "Fetching tests from https://github.com/duerpei/yan_test.git"}
- {"dt": "2023-03-09T05:56:05.818257", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://github.com/duerpei/yan_test.git /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0/tests/0_check_disk_space'"}
- {"dt": "2023-03-09T05:56:08.961510", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0/tests/0_check_disk_space"}
- {"dt": "2023-03-09T05:56:08.979354", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0/tests/0_check_disk_space/./check_space.yaml"}
- {"dt": "2023-03-09T05:56:08.982511", "lvl": "debug", "msg": "uuid=32191_1.5.4.1 testdef=None"}
- {"dt": "2023-03-09T05:56:08.983619", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T05:56:08.984379", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.17", "extra": {"commit": "404e625f36ab5c06fd87440a3e8e5d327dd1676c", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:08.986414", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:08.992123", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:08.992800", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "uuid": "32191_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:08.994501", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.001549", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.002300", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "skipped test-install-overlay": "32191_1.5.4.1", "uuid": "32191_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:09.004143", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.010799", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0/tests/0_check_disk_space test_uuid 32191_1.5.4.1"}
- {"dt": "2023-03-09T05:56:09.012410", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.013131", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0/tests/0_check_disk_space/run.sh", "from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "uuid": "32191_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:09.014543", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-09T05:56:09.015214", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32191/lava-overlay-wt1chqjp/lava-32191/0 for stage 0"}
- {"dt": "2023-03-09T05:56:09.016225", "lvl": "debug", "msg": "- 0_check_disk_space"}
- {"dt": "2023-03-09T05:56:09.017298", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T05:56:09.018176", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.067944", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.068903", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.070412", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.071369", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T05:56:09.072221", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.073049", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.073902", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.074681", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.075638", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:46) [common]"}
- {"dt": "2023-03-09T05:56:09.076329", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-03-09T05:56:09.076974", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61999, NBD-ROOT: 32191/nbd-deploy-__pkqsi4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T05:56:09.080541", "lvl": "debug", "msg": "nbd-server 61999 /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T05:56:09.105022", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-09T05:56:09.108952", "lvl": "debug", "msg": "output: ** (process:4560): WARNING **: 05:56:09.102: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-03-09T05:56:09.110481", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-09T05:56:09.112017", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-03-09T05:56:09.114386", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.116343", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:14) [common]"}
- {"dt": "2023-03-09T05:56:09.117187", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T05:56:09.117932", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T05:56:09.118618", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.120026", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T05:56:09.121233", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-03-09T05:56:09.121830", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T05:56:09.122347", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2023-03-09T05:56:09.122840", "lvl": "debug", "msg": "- {DTB}: 32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:56:09.124206", "lvl": "debug", "msg": "- {INITRD}: 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:56:09.124733", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2023-03-09T05:56:09.125196", "lvl": "debug", "msg": "- {KERNEL}: 32191/nbd-deploy-__pkqsi4/kernel/Image"}
- {"dt": "2023-03-09T05:56:09.125649", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-03-09T05:56:09.126117", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-03-09T05:56:09.126568", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61999"}
- {"dt": "2023-03-09T05:56:09.127940", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-03-09T05:56:09.128492", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-03-09T05:56:09.128967", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2023-03-09T05:56:09.129429", "lvl": "debug", "msg": "- {RAMDISK}: 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:56:09.129893", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-03-09T05:56:09.130369", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-03-09T05:56:09.130833", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-03-09T05:56:09.132325", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-03-09T05:56:09.132833", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-03-09T05:56:09.133296", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-03-09T05:56:09.133771", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-03-09T05:56:09.134229", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:09.134694", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:09.136022", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-03-09T05:56:09.136540", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T05:56:09.137003", "lvl": "info", "msg": "- tftp 0x48080000 32191/nbd-deploy-__pkqsi4/kernel/Image"}
- {"dt": "2023-03-09T05:56:09.137468", "lvl": "info", "msg": "- tftp 0x4c000000 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:56:09.137932", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T05:56:09.138380", "lvl": "info", "msg": "- tftp 0x48000000 32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:56:09.138827", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T05:56:09.140357", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T05:56:09.141047", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:56:09.141625", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x48000000", "kernel_addr": "0x48080000", "ramdisk_addr": "0x4c000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:09.143629", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T05:56:09.144373", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2023-03-09T05:56:12.253910", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-03-09T05:56:12.256189", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T05:56:12.257303", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T05:56:12.258388", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T05:56:12.259398", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T05:56:12.261193", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2023-03-09T05:56:15.672529", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T05:56:16.745016", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T05:56:16.782237", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-03-09T05:56:16.885480", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-03-09T05:56:16.887832", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.63", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:56:16.892120", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-03-09T05:56:16.894626", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-03-09T05:56:16.895885", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-03-09T05:56:16.896556", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-03-09T05:56:16.898271", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-03-09T05:56:16.899026", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-03-09T05:56:16.899622", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-03-09T05:56:17.005589", "lvl": "target", "msg": "\0[    0.000144] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2023-03-09T05:56:17.007386", "lvl": "target", "msg": "[    0.004580] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2023-03-09T05:56:17.009432", "lvl": "target", "msg": "[    0.010113] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-03-09T05:56:17.012348", "lvl": "target", "msg": "[    0.014776] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2023-03-09T05:56:17.014210", "lvl": "target", "msg": "[    0.019548] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2023-03-09T05:56:17.016085", "lvl": "target", "msg": "[    0.024716] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-03-09T05:56:17.017946", "lvl": "target", "msg": "[    0.028757] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-03-09T05:56:17.019661", "lvl": "target", "msg": "[    0.034733] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.064139", "lvl": "target", "msg": "[    0.040595] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.066031", "lvl": "target", "msg": "[    0.046470] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.067757", "lvl": "target", "msg": "[    0.052345] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.069214", "lvl": "target", "msg": "[    0.058262] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2023-03-09T05:56:17.070354", "lvl": "target", "msg": "[    0.073565] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-03-09T05:56:17.071389", "lvl": "target", "msg": "[    0.083094] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-03-09T05:56:17.072067", "lvl": "target", "msg": "[    0.086393] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-03-09T05:56:17.072849", "lvl": "target", "msg": "[    0.091836] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-03-09T05:56:17.081589", "lvl": "target", "msg": "[    0.097193] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-03-09T05:56:17.122528", "lvl": "target", "msg": "[    0.114665] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2023-03-09T05:56:17.124465", "lvl": "target", "msg": "[    0.118309] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2023-03-09T05:56:17.125818", "lvl": "target", "msg": "[    0.123497] NOTICE:  BL2: Normal boot"}
- {"dt": "2023-03-09T05:56:17.127124", "lvl": "target", "msg": "[    0.127132] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2023-03-09T05:56:17.128068", "lvl": "target", "msg": "[    0.133680] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2023-03-09T05:56:17.128979", "lvl": "target", "msg": "[    0.139796] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2023-03-09T05:56:17.130058", "lvl": "target", "msg": "[    0.147396] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2023-03-09T05:56:17.140562", "lvl": "target", "msg": "[    0.156373] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2023-03-09T05:56:17.172711", "lvl": "target", "msg": "[    0.187300] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2023-03-09T05:56:17.187515", "lvl": "target", "msg": "[    0.216008] NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-03-09T05:56:17.425631", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:17.427719", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:17.429097", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2023-03-09T05:56:17.430455", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:17.432751", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-03-09T05:56:17.434354", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-09T05:56:17.796858", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2023-03-09T05:56:17.798864", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2023-03-09T05:56:17.801348", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.803322", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.805070", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-09T05:56:17.806824", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:17.830813", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2023-03-09T05:56:18.161780", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-03-09T05:56:18.163934", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-03-09T05:56:18.165724", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-03-09T05:56:18.168360", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-03-09T05:56:18.211968", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-03-09T05:56:18.214688", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-09T05:56:18.267207", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-03-09T05:56:18.269929", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T05:56:18.272587", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-03-09T05:56:18.274600", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-03-09T05:56:18.276801", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-03-09T05:56:18.291513", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-03-09T05:56:18.293814", "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-03-09T05:56:18.295299", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-03-09T05:56:18.397627", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-03-09T05:56:18.399911", "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:51)"}
- {"dt": "2023-03-09T05:56:18.419068", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-03-09T05:56:18.420078", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-03-09T05:56:18.521699", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:18.523747", "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:51)"}
- {"dt": "2023-03-09T05:56:18.531048", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:18.533222", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-03-09T05:56:18.634985", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:18.637198", "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-03-09T05:56:18.642782", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T05:56:18.645375", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-03-09T05:56:18.746854", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-03-09T05:56:18.748647", "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-03-09T05:56:18.754402", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-03-09T05:56:24.770624", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-03-09T05:56:24.786489", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-03-09T05:56:25.042039", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-03-09T05:56:25.536680", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-03-09T05:56:26.079165", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1282 ms)"}
- {"dt": "2023-03-09T05:56:26.081721", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-03-09T05:56:26.184793", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T05:56:26.187156", "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:43)"}
- {"dt": "2023-03-09T05:56:26.206825", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T05:56:26.208796", "lvl": "input", "msg": "tftp 0x48080000 32191/nbd-deploy-__pkqsi4/kernel/Image\n"}
- {"dt": "2023-03-09T05:56:26.311105", "lvl": "target", "msg": "=> tftp 0x48080000 32191/nbd-deploy-__pkqsi4/kernel/Image"}
- {"dt": "2023-03-09T05:56:26.313352", "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:43)"}
- {"dt": "2023-03-09T05:56:26.318454", "lvl": "target", "msg": "tftp 0x48080000 32191/nbd-deploy-__pkqsi4/kernel/Image"}
- {"dt": "2023-03-09T05:56:26.350037", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T05:56:26.351789", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T05:56:26.354048", "lvl": "target", "msg": "Filename '32191/nbd-deploy-__pkqsi4/kernel/Image'."}
- {"dt": "2023-03-09T05:56:26.355449", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2023-03-09T05:56:31.682780", "lvl": "target", "msg": "Loading: *\bT #################################################################"}
- {"dt": "2023-03-09T05:56:32.002076", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:32.321092", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:32.656324", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:32.975714", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:33.294751", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:33.629867", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:33.949085", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:34.284361", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:34.603536", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:34.938611", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:35.258037", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:35.577009", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:35.912121", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:36.231044", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:36.550518", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:36.869691", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:37.204937", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:37.524094", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:37.859382", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:38.162439", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:38.481975", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:38.816934", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:39.136037", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:39.455281", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:39.774638", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:40.109723", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:40.429039", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:40.764132", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:41.082849", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:41.418560", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:41.737696", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:42.057012", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:42.392146", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:42.711343", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:43.046494", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:43.365709", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:43.685142", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:44.020102", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:44.339462", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:44.642676", "lvl": "target", "msg": "\t ##############################################################"}
- {"dt": "2023-03-09T05:56:44.644832", "lvl": "target", "msg": "\t 2 MiB/s"}
- {"dt": "2023-03-09T05:56:44.646656", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T05:56:44.657957", "lvl": "target", "msg": "Bytes transferred = 39076352 (2544200 hex)"}
- {"dt": "2023-03-09T05:56:44.659209", "lvl": "input", "msg": "tftp 0x4c000000 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-03-09T05:56:44.760808", "lvl": "target", "msg": "=> tftp 0x4c000000 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:56:44.763165", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:24)"}
- {"dt": "2023-03-09T05:56:44.804746", "lvl": "target", "msg": "tftp 0x4c000000 32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T05:56:44.806579", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T05:56:44.809151", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T05:56:44.809967", "lvl": "target", "msg": "Filename '32191/nbd-deploy-__pkqsi4/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-03-09T05:56:44.810732", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2023-03-09T05:56:45.126441", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-03-09T05:56:45.445108", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T05:56:45.506089", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-03-09T05:56:45.508124", "lvl": "target", "msg": "\t 2.8 MiB/s"}
- {"dt": "2023-03-09T05:56:45.509906", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T05:56:45.512547", "lvl": "target", "msg": "Bytes transferred = 2057818 (1f665a hex)"}
- {"dt": "2023-03-09T05:56:45.515243", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-03-09T05:56:45.618136", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T05:56:45.620418", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:23)"}
- {"dt": "2023-03-09T05:56:45.636032", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T05:56:45.638407", "lvl": "input", "msg": "tftp 0x48000000 32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb\n"}
- {"dt": "2023-03-09T05:56:45.741482", "lvl": "target", "msg": "=> tftp 0x48000000 32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:56:45.743742", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:23)"}
- {"dt": "2023-03-09T05:56:45.747740", "lvl": "target", "msg": "tftp 0x48000000 32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T05:56:45.785400", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T05:56:45.787846", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T05:56:45.789388", "lvl": "target", "msg": "Filename '32191/nbd-deploy-__pkqsi4/dtb/r8a77951-agl-refhw.dtb'."}
- {"dt": "2023-03-09T05:56:45.790769", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2023-03-09T05:56:45.832020", "lvl": "target", "msg": "Loading: *\b#######"}
- {"dt": "2023-03-09T05:56:45.833468", "lvl": "target", "msg": "\t 2.2 MiB/s"}
- {"dt": "2023-03-09T05:56:45.834825", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T05:56:45.836257", "lvl": "target", "msg": "Bytes transferred = 88958 (15b7e hex)"}
- {"dt": "2023-03-09T05:56:45.838113", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 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-03-09T05:56:45.940709", "lvl": "target", "msg": "=>setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T05:56:45.942963", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:23)"}
- {"dt": "2023-03-09T05:56:45.962268", "lvl": "target", "msg": " setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T05:56:45.964327", "lvl": "input", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000\n"}
- {"dt": "2023-03-09T05:56:46.066791", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T05:56:46.069172", "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-03-09T05:56:46.071229", "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:23)"}
- {"dt": "2023-03-09T05:56:46.075334", "lvl": "target", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T05:56:46.214435", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2023-03-09T05:56:46.217194", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2023-03-09T05:56:46.219166", "lvl": "target", "msg": "   Loading Ramdisk to bfc0e000, end bfe0465a ... OK"}
- {"dt": "2023-03-09T05:56:46.221271", "lvl": "target", "msg": "   Loading Device Tree to 00000000bfbf5000, end 00000000bfc0db7d ... OK"}
- {"dt": "2023-03-09T05:56:46.223174", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:46.224987", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2023-03-09T05:56:46.226780", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:56:46.228310", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:28) [common]"}
- {"dt": "2023-03-09T05:56:46.229155", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:23) [common]"}
- {"dt": "2023-03-09T05:56:46.229835", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-09T05:56:46.230466", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]', '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-03-09T05:56:46.231167", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]', '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:20:00)"}
- {"dt": "2023-03-09T05:56:47.521144", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2023-03-09T05:56:47.523513", "lvl": "target", "msg": "[    0.000000] Linux version 5.10.41-yocto-standard (oe-user@oe-host) (aarch64-agl-linux-gcc (GCC) 11.3.0, GNU ld (GNU Binutils) 2.38.20220708) #1 SMP PREEMPT Mon Jan 30 17:33:20 UTC 2023"}
- {"dt": "2023-03-09T05:56:47.525497", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a77951 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-09T05:56:47.527353", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2023-03-09T05:56:47.529474", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2023-03-09T05:56:47.531973", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:22) [common]"}
- {"dt": "2023-03-09T05:56:47.532665", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2023-03-09T05:56:47.533377", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2023-03-09T05:56:47.534089", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2023-03-09T05:56:47.534771", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2023-03-09T05:56:47.535515", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2023-03-09T05:56:47.536171", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@h3ulcb:~", "h3ulcb:~#", "/ #", "login:", "Login incorrect"]}
- {"dt": "2023-03-09T05:56:47.537379", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:22)"}
- {"dt": "2023-03-09T05:56:47.563864", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T05:56:47.565346", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2023-03-09T05:56:47.567083", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T05:56:47.568472", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2023-03-09T05:56:47.570033", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T05:56:47.606237", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2023-03-09T05:56:47.608197", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T05:56:47.609073", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efdfb00-0x77efe1fff]"}
- {"dt": "2023-03-09T05:56:47.609897", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2023-03-09T05:56:47.610743", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2023-03-09T05:56:47.611637", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2023-03-09T05:56:47.612476", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T05:56:47.613292", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2023-03-09T05:56:47.614291", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2023-03-09T05:56:47.649181", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2023-03-09T05:56:47.650513", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000054000000-0x0000000056ffffff]"}
- {"dt": "2023-03-09T05:56:47.651666", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2023-03-09T05:56:47.652731", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2023-03-09T05:56:47.653986", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2023-03-09T05:56:47.655130", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T05:56:47.692217", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T05:56:47.693314", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2023-03-09T05:56:47.695381", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2023-03-09T05:56:47.696994", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2023-03-09T05:56:47.698462", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2023-03-09T05:56:47.700035", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2023-03-09T05:56:47.701467", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 33 pages/cpu s94296 r8192 d32680 u135168"}
- {"dt": "2023-03-09T05:56:47.702955", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2023-03-09T05:56:47.704703", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2023-03-09T05:56:47.734815", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v2"}
- {"dt": "2023-03-09T05:56:47.736252", "lvl": "target", "msg": "[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923"}
- {"dt": "2023-03-09T05:56:47.737300", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2032128"}
- {"dt": "2023-03-09T05:56:47.738339", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2023-03-09T05:56:47.740932", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw"}
- {"dt": "2023-03-09T05:56:47.779082", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2023-03-09T05:56:47.780573", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2023-03-09T05:56:47.781899", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2023-03-09T05:56:47.783477", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000bbbf5000-0x00000000bfbf5000] (64MB)"}
- {"dt": "2023-03-09T05:56:47.820979", "lvl": "target", "msg": "[    0.000000] Memory: 7268892K/8257536K available (17600K kernel code, 3792K rwdata, 9264K rodata, 7360K init, 574K bss, 316900K reserved, 671744K cma-reserved)"}
- {"dt": "2023-03-09T05:56:47.823860", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2023-03-09T05:56:47.825723", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2023-03-09T05:56:47.827547", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2023-03-09T05:56:47.829236", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2023-03-09T05:56:47.830988", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2023-03-09T05:56:47.832719", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2023-03-09T05:56:47.834734", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2023-03-09T05:56:47.863901", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2023-03-09T05:56:47.865435", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2023-03-09T05:56:47.866528", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2023-03-09T05:56:47.867706", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2023-03-09T05:56:47.868784", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x384/0x548 with crng_init=0"}
- {"dt": "2023-03-09T05:56:47.870019", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2023-03-09T05:56:47.906996", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2023-03-09T05:56:47.910237", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2023-03-09T05:56:47.912207", "lvl": "target", "msg": "[    0.000160] Console: colour dummy device 80x25"}
- {"dt": "2023-03-09T05:56:47.913909", "lvl": "target", "msg": "[    0.000242] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2023-03-09T05:56:47.915730", "lvl": "target", "msg": "[    0.000253] pid_max: default: 32768 minimum: 301"}
- {"dt": "2023-03-09T05:56:47.917419", "lvl": "target", "msg": "[    0.000306] LSM: Security Framework initializing"}
- {"dt": "2023-03-09T05:56:47.919050", "lvl": "target", "msg": "[    0.000333] SELinux:  Initializing."}
- {"dt": "2023-03-09T05:56:47.919777", "lvl": "target", "msg": "[    0.000372] LSM support for eBPF active"}
- {"dt": "2023-03-09T05:56:47.949407", "lvl": "target", "msg": "[    0.000441] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T05:56:47.951711", "lvl": "target", "msg": "[    0.000486] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T05:56:47.952920", "lvl": "target", "msg": "[    0.002019] rcu: Hierarchical SRCU implementation."}
- {"dt": "2023-03-09T05:56:47.954028", "lvl": "target", "msg": "[    0.003677] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2023-03-09T05:56:47.955172", "lvl": "target", "msg": "[    0.005668] EFI services will not be available."}
- {"dt": "2023-03-09T05:56:47.956230", "lvl": "target", "msg": "[    0.005998] smp: Bringing up secondary CPUs ..."}
- {"dt": "2023-03-09T05:56:47.957297", "lvl": "target", "msg": "[    0.006469] Detected PIPT I-cache on CPU1"}
- {"dt": "2023-03-09T05:56:47.992758", "lvl": "target", "msg": "[    0.006523] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2023-03-09T05:56:47.994330", "lvl": "target", "msg": "[    0.007015] Detected PIPT I-cache on CPU2"}
- {"dt": "2023-03-09T05:56:47.997313", "lvl": "target", "msg": "[    0.007040] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2023-03-09T05:56:47.998976", "lvl": "target", "msg": "[    0.007483] Detected PIPT I-cache on CPU3"}
- {"dt": "2023-03-09T05:56:48.000529", "lvl": "target", "msg": "[    0.007505] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2023-03-09T05:56:48.002073", "lvl": "target", "msg": "[    0.007946] CPU features: detected: ARM erratum 845719"}
- {"dt": "2023-03-09T05:56:48.003438", "lvl": "target", "msg": "[    0.007959] Detected VIPT I-cache on CPU4"}
- {"dt": "2023-03-09T05:56:48.003968", "lvl": "target", "msg": "[    0.008006] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2023-03-09T05:56:48.004613", "lvl": "target", "msg": "[    0.008501] Detected VIPT I-cache on CPU5"}
- {"dt": "2023-03-09T05:56:48.035829", "lvl": "target", "msg": "[    0.008530] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2023-03-09T05:56:48.037114", "lvl": "target", "msg": "[    0.009003] Detected VIPT I-cache on CPU6"}
- {"dt": "2023-03-09T05:56:48.037735", "lvl": "target", "msg": "[    0.009031] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2023-03-09T05:56:48.038289", "lvl": "target", "msg": "[    0.009477] Detected VIPT I-cache on CPU7"}
- {"dt": "2023-03-09T05:56:48.038818", "lvl": "target", "msg": "[    0.009504] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2023-03-09T05:56:48.039887", "lvl": "target", "msg": "[    0.009586] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2023-03-09T05:56:48.040851", "lvl": "target", "msg": "[    0.009614] SMP: Total of 8 processors activated."}
- {"dt": "2023-03-09T05:56:48.041917", "lvl": "target", "msg": "[    0.009620] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2023-03-09T05:56:48.078607", "lvl": "target", "msg": "[    0.009626] CPU features: detected: CRC32 instructions"}
- {"dt": "2023-03-09T05:56:48.081043", "lvl": "target", "msg": "[    0.009631] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2023-03-09T05:56:48.082323", "lvl": "target", "msg": "[    0.022856] CPU: All CPU(s) started at EL2"}
- {"dt": "2023-03-09T05:56:48.083584", "lvl": "target", "msg": "[    0.022893] alternatives: patching kernel code"}
- {"dt": "2023-03-09T05:56:48.084785", "lvl": "target", "msg": "[    0.023965] devtmpfs: initialized"}
- {"dt": "2023-03-09T05:56:48.085977", "lvl": "target", "msg": "[    0.031370] KASLR disabled due to lack of seed"}
- {"dt": "2023-03-09T05:56:48.087235", "lvl": "target", "msg": "[    0.031507] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2023-03-09T05:56:48.088650", "lvl": "target", "msg": "[    0.031521] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.121747", "lvl": "target", "msg": "[    0.040201] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2023-03-09T05:56:48.124031", "lvl": "target", "msg": "[    0.041615] DMI not present or invalid."}
- {"dt": "2023-03-09T05:56:48.125238", "lvl": "target", "msg": "[    0.041986] NET: Registered protocol family 16"}
- {"dt": "2023-03-09T05:56:48.126321", "lvl": "target", "msg": "[    0.042947] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2023-03-09T05:56:48.127471", "lvl": "target", "msg": "[    0.043118] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2023-03-09T05:56:48.128574", "lvl": "target", "msg": "[    0.043402] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2023-03-09T05:56:48.129670", "lvl": "target", "msg": "[    0.043441] audit: initializing netlink subsys (disabled)"}
- {"dt": "2023-03-09T05:56:48.164667", "lvl": "target", "msg": "[    0.043617] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2023-03-09T05:56:48.167653", "lvl": "target", "msg": "[    0.044731] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2023-03-09T05:56:48.169287", "lvl": "target", "msg": "[    0.045253] cpuidle: using governor menu"}
- {"dt": "2023-03-09T05:56:48.170795", "lvl": "target", "msg": "[    0.045422] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2023-03-09T05:56:48.172406", "lvl": "target", "msg": "[    0.045545] ASID allocator initialised with 65536 entries"}
- {"dt": "2023-03-09T05:56:48.173961", "lvl": "target", "msg": "[    0.046765] Serial: AMBA PL011 UART driver"}
- {"dt": "2023-03-09T05:56:48.175377", "lvl": "target", "msg": "[    0.089371] sh-pfc e6060000.pinctrl: r8a77951_pfc support registered"}
- {"dt": "2023-03-09T05:56:48.176038", "lvl": "target", "msg": "[    0.103917] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T05:56:48.208410", "lvl": "target", "msg": "[    0.103924] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T05:56:48.209423", "lvl": "target", "msg": "[    0.103930] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T05:56:48.210354", "lvl": "target", "msg": "[    0.103935] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T05:56:48.211510", "lvl": "target", "msg": "[    0.104918] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2023-03-09T05:56:48.212439", "lvl": "target", "msg": "[    0.107129] ACPI: Interpreter disabled."}
- {"dt": "2023-03-09T05:56:48.213336", "lvl": "target", "msg": "[    0.110867] rcar-avs: use avs value: 0"}
- {"dt": "2023-03-09T05:56:48.214240", "lvl": "target", "msg": "[    0.110877] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2023-03-09T05:56:48.215353", "lvl": "target", "msg": "[    0.110884] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2023-03-09T05:56:48.250456", "lvl": "target", "msg": "[    0.110892] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2023-03-09T05:56:48.253090", "lvl": "target", "msg": "[    0.110901] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2023-03-09T05:56:48.254530", "lvl": "target", "msg": "[    0.110978] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2023-03-09T05:56:48.255940", "lvl": "target", "msg": "[    0.112347] iommu: Default domain type: Translated "}
- {"dt": "2023-03-09T05:56:48.257292", "lvl": "target", "msg": "[    0.112891] ipmmu-vmsa e67b0000.iommu: IPMMU context 0 is reserved"}
- {"dt": "2023-03-09T05:56:48.258640", "lvl": "target", "msg": "[    0.113847] vgaarb: loaded"}
- {"dt": "2023-03-09T05:56:48.260070", "lvl": "target", "msg": "[    0.114042] SCSI subsystem initialized"}
- {"dt": "2023-03-09T05:56:48.261422", "lvl": "target", "msg": "[    0.114303] usbcore: registered new interface driver usbfs"}
- {"dt": "2023-03-09T05:56:48.293748", "lvl": "target", "msg": "[    0.114327] usbcore: registered new interface driver hub"}
- {"dt": "2023-03-09T05:56:48.295936", "lvl": "target", "msg": "[    0.114351] usbcore: registered new device driver usb"}
- {"dt": "2023-03-09T05:56:48.297122", "lvl": "target", "msg": "[    0.117712] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2023-03-09T05:56:48.298228", "lvl": "target", "msg": "[    0.118215] mc: Linux media interface: v0.10"}
- {"dt": "2023-03-09T05:56:48.299383", "lvl": "target", "msg": "[    0.118234] videodev: Linux video capture interface: v2.00"}
- {"dt": "2023-03-09T05:56:48.300465", "lvl": "target", "msg": "[    0.118280] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2023-03-09T05:56:48.301530", "lvl": "target", "msg": "[    0.118285] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2023-03-09T05:56:48.302808", "lvl": "target", "msg": "[    0.118294] PTP clock support registered"}
- {"dt": "2023-03-09T05:56:48.336338", "lvl": "target", "msg": "[    0.118451] EDAC MC: Ver: 3.0.0"}
- {"dt": "2023-03-09T05:56:48.337481", "lvl": "target", "msg": "[    0.120269] FPGA manager framework"}
- {"dt": "2023-03-09T05:56:48.339637", "lvl": "target", "msg": "[    0.120319] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2023-03-09T05:56:48.340808", "lvl": "target", "msg": "[    0.120894] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2023-03-09T05:56:48.341874", "lvl": "target", "msg": "[    0.562972] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2023-03-09T05:56:48.343014", "lvl": "target", "msg": "[    0.563020] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2023-03-09T05:56:48.344106", "lvl": "target", "msg": "[    0.563191] pnp: PnP ACPI: disabled"}
- {"dt": "2023-03-09T05:56:48.345190", "lvl": "target", "msg": "[    0.567266] NET: Registered protocol family 2"}
- {"dt": "2023-03-09T05:56:48.346454", "lvl": "target", "msg": "[    0.567650] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.380193", "lvl": "target", "msg": "[    0.569879] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.381288", "lvl": "target", "msg": "[    0.569972] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.382492", "lvl": "target", "msg": "[    0.570251] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.383586", "lvl": "target", "msg": "[    0.570832] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2023-03-09T05:56:48.384571", "lvl": "target", "msg": "[    0.570963] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.422265", "lvl": "target", "msg": "[    0.571073] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T05:56:48.423719", "lvl": "target", "msg": "[    0.571294] NET: Registered protocol family 1"}
- {"dt": "2023-03-09T05:56:48.426283", "lvl": "target", "msg": "[    0.571619] RPC: Registered named UNIX socket transport module."}
- {"dt": "2023-03-09T05:56:48.427765", "lvl": "target", "msg": "[    0.571625] RPC: Registered udp transport module."}
- {"dt": "2023-03-09T05:56:48.429118", "lvl": "target", "msg": "[    0.571629] RPC: Registered tcp transport module."}
- {"dt": "2023-03-09T05:56:48.430470", "lvl": "target", "msg": "[    0.571633] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2023-03-09T05:56:48.431885", "lvl": "target", "msg": "[    0.571642] PCI: CLS 0 bytes, default 64"}
- {"dt": "2023-03-09T05:56:48.433265", "lvl": "target", "msg": "[    0.571794] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2023-03-09T05:56:48.434862", "lvl": "target", "msg": "[    0.571971] rootfs image is not initramfs (no cpio magic); looks like an initrd"}
- {"dt": "2023-03-09T05:56:48.465233", "lvl": "target", "msg": "[    0.574988] Freeing initrd memory: 2008K"}
- {"dt": "2023-03-09T05:56:48.467311", "lvl": "target", "msg": "[    0.575858] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2023-03-09T05:56:48.468394", "lvl": "target", "msg": "[    0.576140] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2023-03-09T05:56:48.469385", "lvl": "target", "msg": "[    0.576750] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2023-03-09T05:56:48.470366", "lvl": "target", "msg": "[    0.577637] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2023-03-09T05:56:48.471400", "lvl": "target", "msg": "[    0.577809] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2023-03-09T05:56:48.472378", "lvl": "target", "msg": "[    0.581021] Initialise system trusted keyrings"}
- {"dt": "2023-03-09T05:56:48.508200", "lvl": "target", "msg": "[    0.581154] workingset: timestamp_bits=42 max_order=21 bucket_order=0"}
- {"dt": "2023-03-09T05:56:48.510822", "lvl": "target", "msg": "[    0.584554] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2023-03-09T05:56:48.512317", "lvl": "target", "msg": "[    0.584973] NFS: Registering the id_resolver key type"}
- {"dt": "2023-03-09T05:56:48.513650", "lvl": "target", "msg": "[    0.584991] Key type id_resolver registered"}
- {"dt": "2023-03-09T05:56:48.515035", "lvl": "target", "msg": "[    0.584995] Key type id_legacy registered"}
- {"dt": "2023-03-09T05:56:48.516373", "lvl": "target", "msg": "[    0.585051] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2023-03-09T05:56:48.517742", "lvl": "target", "msg": "[    0.585057] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2023-03-09T05:56:48.519325", "lvl": "target", "msg": "[    0.585210] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2023-03-09T05:56:48.551049", "lvl": "target", "msg": "[    0.611062] NET: Registered protocol family 38"}
- {"dt": "2023-03-09T05:56:48.552045", "lvl": "target", "msg": "[    0.611072] Key type asymmetric registered"}
- {"dt": "2023-03-09T05:56:48.553862", "lvl": "target", "msg": "[    0.611078] Asymmetric key parser 'x509' registered"}
- {"dt": "2023-03-09T05:56:48.554844", "lvl": "target", "msg": "[    0.611125] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2023-03-09T05:56:48.555841", "lvl": "target", "msg": "[    0.611133] io scheduler mq-deadline registered"}
- {"dt": "2023-03-09T05:56:48.556736", "lvl": "target", "msg": "[    0.611137] io scheduler kyber registered"}
- {"dt": "2023-03-09T05:56:48.557640", "lvl": "target", "msg": "[    0.626275] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-09T05:56:48.558536", "lvl": "target", "msg": "[    0.626586] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2023-03-09T05:56:48.559675", "lvl": "target", "msg": "[    0.626873] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2023-03-09T05:56:48.593973", "lvl": "target", "msg": "[    0.627156] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-09T05:56:48.595882", "lvl": "target", "msg": "[    0.627437] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2023-03-09T05:56:48.596896", "lvl": "target", "msg": "[    0.627716] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2023-03-09T05:56:48.597822", "lvl": "target", "msg": "[    0.627891] gpio-369 (wlan-pwr-en): hogged as output/high"}
- {"dt": "2023-03-09T05:56:48.598719", "lvl": "target", "msg": "[    0.627910] gpio-381 (can-1-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-09T05:56:48.599687", "lvl": "target", "msg": "[    0.627927] gpio-372 (can-2-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-09T05:56:48.600772", "lvl": "target", "msg": "[    0.628059] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2023-03-09T05:56:48.601726", "lvl": "target", "msg": "[    0.628328] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2023-03-09T05:56:48.636989", "lvl": "target", "msg": "[    0.630582] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2023-03-09T05:56:48.639451", "lvl": "target", "msg": "[    0.630604] rcar-pcie fe000000.pcie:       IO 0x00fe100000..0x00fe1fffff -> 0x0000000000"}
- {"dt": "2023-03-09T05:56:48.640829", "lvl": "target", "msg": "[    0.630622] rcar-pcie fe000000.pcie:      MEM 0x00fe200000..0x00fe3fffff -> 0x00fe200000"}
- {"dt": "2023-03-09T05:56:48.642036", "lvl": "target", "msg": "[    0.630637] rcar-pcie fe000000.pcie:      MEM 0x0030000000..0x0037ffffff -> 0x0030000000"}
- {"dt": "2023-03-09T05:56:48.643310", "lvl": "target", "msg": "[    0.630651] rcar-pcie fe000000.pcie:      MEM 0x0038000000..0x003fffffff -> 0x0038000000"}
- {"dt": "2023-03-09T05:56:48.680084", "lvl": "target", "msg": "[    0.630664] rcar-pcie fe000000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-09T05:56:48.683106", "lvl": "target", "msg": "[    0.630701] rcar-pcie fe000000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-09T05:56:48.684752", "lvl": "target", "msg": "[    0.630708] rcar-pcie fe000000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-09T05:56:48.686286", "lvl": "target", "msg": "[    0.696526] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2023-03-09T05:56:48.687889", "lvl": "target", "msg": "[    0.696692] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2023-03-09T05:56:48.689437", "lvl": "target", "msg": "[    0.696711] rcar-pcie ee800000.pcie:       IO 0x00ee900000..0x00ee9fffff -> 0x0000000000"}
- {"dt": "2023-03-09T05:56:48.691289", "lvl": "target", "msg": "[    0.696727] rcar-pcie ee800000.pcie:      MEM 0x00eea00000..0x00eebfffff -> 0x00eea00000"}
- {"dt": "2023-03-09T05:56:48.723937", "lvl": "target", "msg": "[    0.696740] rcar-pcie ee800000.pcie:      MEM 0x00c0000000..0x00c7ffffff -> 0x00c0000000"}
- {"dt": "2023-03-09T05:56:48.725074", "lvl": "target", "msg": "[    0.696750] rcar-pcie ee800000.pcie:      MEM 0x00c8000000..0x00cfffffff -> 0x00c8000000"}
- {"dt": "2023-03-09T05:56:48.726286", "lvl": "target", "msg": "[    0.696762] rcar-pcie ee800000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-09T05:56:48.727529", "lvl": "target", "msg": "[    0.696788] rcar-pcie ee800000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-09T05:56:48.728720", "lvl": "target", "msg": "[    0.696795] rcar-pcie ee800000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-09T05:56:48.730115", "lvl": "target", "msg": "[    0.760520] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2023-03-09T05:56:48.731414", "lvl": "target", "msg": "[    0.762821] EINJ: ACPI disabled."}
- {"dt": "2023-03-09T05:56:48.767140", "lvl": "target", "msg": "[    0.794653] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2023-03-09T05:56:48.768815", "lvl": "target", "msg": "[    0.797098] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2023-03-09T05:56:48.770335", "lvl": "target", "msg": "[    0.797462] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 44, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T05:56:48.771928", "lvl": "target", "msg": "[    0.797633] serial serial0: tty port ttySC4 registered"}
- {"dt": "2023-03-09T05:56:48.773719", "lvl": "target", "msg": "[    0.798052] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 45, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T05:56:48.775381", "lvl": "target", "msg": "[    0.798586] e6560000.serial: ttySC5 at MMIO 0xe6560000 (irq = 46, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T05:56:48.817240", "lvl": "target", "msg": "[    0.799177] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 134, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T05:56:48.818430", "lvl": "target", "msg": "[    0.799698] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 135, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T05:56:48.819613", "lvl": "target", "msg": "[    2.101891] printk: console [ttySC0] enabled"}
- {"dt": "2023-03-09T05:56:48.820900", "lvl": "target", "msg": "[    2.106630] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 136, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T05:56:48.822008", "lvl": "target", "msg": "[    2.116085] msm_serial: driver initialized"}
- {"dt": "2023-03-09T05:56:48.823345", "lvl": "target", "msg": "[    2.125829] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-09T05:56:48.854424", "lvl": "target", "msg": "[    2.135671] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-09T05:56:48.857377", "lvl": "target", "msg": "[    2.143838] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-09T05:56:48.859080", "lvl": "target", "msg": "[    2.153639] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-09T05:56:48.860855", "lvl": "target", "msg": "[    2.168433] brd: module loaded"}
- {"dt": "2023-03-09T05:56:48.872814", "lvl": "target", "msg": "[    2.177067] loop: module loaded"}
- {"dt": "2023-03-09T05:56:48.956631", "lvl": "target", "msg": "[    2.227000] megasas: 07.714.04.00-rc1"}
- {"dt": "2023-03-09T05:56:48.958518", "lvl": "target", "msg": "[    2.235751] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2023-03-09T05:56:48.959933", "lvl": "target", "msg": "[    2.240900] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2023-03-09T05:56:48.961125", "lvl": "target", "msg": "[    2.246784] thunder_xcv, ver 1.0"}
- {"dt": "2023-03-09T05:56:48.962298", "lvl": "target", "msg": "[    2.250023] thunder_bgx, ver 1.0"}
- {"dt": "2023-03-09T05:56:48.963576", "lvl": "target", "msg": "[    2.253254] nicpf, ver 1.0"}
- {"dt": "2023-03-09T05:56:48.964795", "lvl": "target", "msg": "[    2.257295] hclge is initializing"}
- {"dt": "2023-03-09T05:56:48.966014", "lvl": "target", "msg": "[    2.260608] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2023-03-09T05:56:48.967293", "lvl": "target", "msg": "[    2.267795] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2023-03-09T05:56:48.968723", "lvl": "target", "msg": "[    2.273106] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-09T05:56:49.001289", "lvl": "target", "msg": "[    2.277959] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2023-03-09T05:56:49.003496", "lvl": "target", "msg": "[    2.283696] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-09T05:56:49.004687", "lvl": "target", "msg": "[    2.288640] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2023-03-09T05:56:49.005767", "lvl": "target", "msg": "[    2.294555] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2023-03-09T05:56:49.006835", "lvl": "target", "msg": "[    2.299927] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2023-03-09T05:56:49.007982", "lvl": "target", "msg": "[    2.305486] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2023-03-09T05:56:49.009274", "lvl": "target", "msg": "[    2.311722] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2023-03-09T05:56:49.010415", "lvl": "target", "msg": "[    2.317995] sky2: driver version 1.30"}
- {"dt": "2023-03-09T05:56:49.055061", "lvl": "target", "msg": "[    2.322910] libphy: ravb_mii: probed"}
- {"dt": "2023-03-09T05:56:49.056720", "lvl": "target", "msg": "[    2.329069] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 129."}
- {"dt": "2023-03-09T05:56:49.057907", "lvl": "target", "msg": "[    2.339191] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2023-03-09T05:56:49.059086", "lvl": "target", "msg": "[    2.346302] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2023-03-09T05:56:49.060214", "lvl": "target", "msg": "[    2.352805] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2023-03-09T05:56:49.061313", "lvl": "target", "msg": "[    2.357264] ehci-platform: EHCI generic platform driver"}
- {"dt": "2023-03-09T05:56:49.096859", "lvl": "target", "msg": "[    2.388957] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.099040", "lvl": "target", "msg": "[    2.394608] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2023-03-09T05:56:49.101306", "lvl": "target", "msg": "[    2.402462] ehci-platform ee080100.usb: irq 182, io mem 0xee080100"}
- {"dt": "2023-03-09T05:56:49.149524", "lvl": "target", "msg": "[    2.420901] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T05:56:49.151627", "lvl": "target", "msg": "[    2.427441] hub 1-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.153436", "lvl": "target", "msg": "[    2.431197] hub 1-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.155284", "lvl": "target", "msg": "[    2.435547] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.157102", "lvl": "target", "msg": "[    2.441206] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2023-03-09T05:56:49.159258", "lvl": "target", "msg": "[    2.449067] ehci-platform ee0a0100.usb: irq 183, io mem 0xee0a0100"}
- {"dt": "2023-03-09T05:56:49.196455", "lvl": "target", "msg": "[    2.468901] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T05:56:49.197962", "lvl": "target", "msg": "[    2.475338] hub 2-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.199048", "lvl": "target", "msg": "[    2.479087] hub 2-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.199985", "lvl": "target", "msg": "[    2.483397] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.200904", "lvl": "target", "msg": "[    2.489053] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2023-03-09T05:56:49.201980", "lvl": "target", "msg": "[    2.496915] ehci-platform ee0c0100.usb: irq 184, io mem 0xee0c0100"}
- {"dt": "2023-03-09T05:56:49.223511", "lvl": "target", "msg": "[    2.516900] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T05:56:49.225934", "lvl": "target", "msg": "[    2.523318] hub 3-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.227474", "lvl": "target", "msg": "[    2.527067] hub 3-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.265471", "lvl": "target", "msg": "[    2.556938] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.268328", "lvl": "target", "msg": "[    2.562582] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2023-03-09T05:56:49.269970", "lvl": "target", "msg": "[    2.570413] ehci-platform ee0e0100.usb: irq 185, io mem 0xee0e0100"}
- {"dt": "2023-03-09T05:56:49.313222", "lvl": "target", "msg": "[    2.588896] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T05:56:49.315312", "lvl": "target", "msg": "[    2.595331] hub 4-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.317094", "lvl": "target", "msg": "[    2.599081] hub 4-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.318686", "lvl": "target", "msg": "[    2.603386] ehci-orion: EHCI orion driver"}
- {"dt": "2023-03-09T05:56:49.320292", "lvl": "target", "msg": "[    2.607641] ehci-exynos: EHCI Exynos driver"}
- {"dt": "2023-03-09T05:56:49.321953", "lvl": "target", "msg": "[    2.611933] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2023-03-09T05:56:49.322859", "lvl": "target", "msg": "[    2.618117] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2023-03-09T05:56:49.323676", "lvl": "target", "msg": "[    2.622590] ohci-platform: OHCI generic platform driver"}
- {"dt": "2023-03-09T05:56:49.338199", "lvl": "target", "msg": "[    2.627993] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T05:56:49.340255", "lvl": "target", "msg": "[    2.634680] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2023-03-09T05:56:49.342196", "lvl": "target", "msg": "[    2.642518] ohci-platform ee080000.usb: irq 182, io mem 0xee080000"}
- {"dt": "2023-03-09T05:56:49.420490", "lvl": "target", "msg": "[    2.735288] hub 5-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.455082", "lvl": "target", "msg": "[    2.739038] hub 5-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.458533", "lvl": "target", "msg": "[    2.743308] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T05:56:49.460497", "lvl": "target", "msg": "[    2.750003] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2023-03-09T05:56:49.462572", "lvl": "target", "msg": "[    2.757851] ohci-platform ee0a0000.usb: irq 183, io mem 0xee0a0000"}
- {"dt": "2023-03-09T05:56:49.537209", "lvl": "target", "msg": "[    2.851275] hub 6-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.571768", "lvl": "target", "msg": "[    2.855025] hub 6-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.574511", "lvl": "target", "msg": "[    2.859285] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T05:56:49.575896", "lvl": "target", "msg": "[    2.865984] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2023-03-09T05:56:49.577603", "lvl": "target", "msg": "[    2.873842] ohci-platform ee0c0000.usb: irq 184, io mem 0xee0c0000"}
- {"dt": "2023-03-09T05:56:49.688417", "lvl": "target", "msg": "[    2.967268] hub 7-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.691690", "lvl": "target", "msg": "[    2.971018] hub 7-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.693554", "lvl": "target", "msg": "[    2.975273] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T05:56:49.695426", "lvl": "target", "msg": "[    2.981971] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2023-03-09T05:56:49.697520", "lvl": "target", "msg": "[    2.989821] ohci-platform ee0e0000.usb: irq 185, io mem 0xee0e0000"}
- {"dt": "2023-03-09T05:56:49.800168", "lvl": "target", "msg": "[    3.083275] hub 8-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.803511", "lvl": "target", "msg": "[    3.087024] hub 8-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.805415", "lvl": "target", "msg": "[    3.091302] ohci-exynos: OHCI Exynos driver"}
- {"dt": "2023-03-09T05:56:49.807312", "lvl": "target", "msg": "[    3.096161] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.809384", "lvl": "target", "msg": "[    3.101394] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2023-03-09T05:56:49.847723", "lvl": "target", "msg": "[    3.124625] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2023-03-09T05:56:49.849049", "lvl": "target", "msg": "[    3.133740] xhci-hcd ee000000.usb: irq 180, io mem 0xee000000"}
- {"dt": "2023-03-09T05:56:49.850262", "lvl": "target", "msg": "[    3.139890] hub 9-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.851745", "lvl": "target", "msg": "[    3.143645] hub 9-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.852988", "lvl": "target", "msg": "[    3.147710] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-09T05:56:49.854207", "lvl": "target", "msg": "[    3.152919] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2023-03-09T05:56:49.855698", "lvl": "target", "msg": "[    3.160373] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2023-03-09T05:56:49.891886", "lvl": "target", "msg": "[    3.166653] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2023-03-09T05:56:49.893073", "lvl": "target", "msg": "[    3.175071] hub 10-0:1.0: USB hub found"}
- {"dt": "2023-03-09T05:56:49.894156", "lvl": "target", "msg": "[    3.178906] hub 10-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T05:56:49.895320", "lvl": "target", "msg": "[    3.183698] usbcore: registered new interface driver usb-storage"}
- {"dt": "2023-03-09T05:56:49.896619", "lvl": "target", "msg": "[    3.189754] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2023-03-09T05:56:49.897742", "lvl": "target", "msg": "[    3.196261] usbserial: USB Serial support registered for generic"}
- {"dt": "2023-03-09T05:56:49.898836", "lvl": "target", "msg": "[    3.202278] usbcore: registered new interface driver cp210x"}
- {"dt": "2023-03-09T05:56:49.937652", "lvl": "target", "msg": "[    3.207834] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2023-03-09T05:56:49.940710", "lvl": "target", "msg": "[    3.215397] renesas_usbhs e6590000.usb: host probed"}
- {"dt": "2023-03-09T05:56:49.942348", "lvl": "target", "msg": "[    3.220261] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2023-03-09T05:56:49.943933", "lvl": "target", "msg": "[    3.225970] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2023-03-09T05:56:49.945463", "lvl": "target", "msg": "[    3.231173] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2023-03-09T05:56:49.947037", "lvl": "target", "msg": "[    3.235979] renesas_usbhs e659c000.usb: host probed"}
- {"dt": "2023-03-09T05:56:49.948591", "lvl": "target", "msg": "[    3.240849] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2023-03-09T05:56:49.950135", "lvl": "target", "msg": "[    3.246559] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2023-03-09T05:56:49.951561", "lvl": "target", "msg": "[    3.251732] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2023-03-09T05:56:49.960643", "lvl": "target", "msg": "[    3.257208] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2023-03-09T05:56:49.964216", "lvl": "target", "msg": "[    3.264385] i2c /dev entries driver"}
- {"dt": "2023-03-09T05:56:50.007571", "lvl": "target", "msg": "[    3.280984] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2023-03-09T05:56:50.010052", "lvl": "target", "msg": "[    3.285211] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2023-03-09T05:56:50.011427", "lvl": "target", "msg": "[    3.290119] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2023-03-09T05:56:50.012608", "lvl": "target", "msg": "[    3.294147] usb usb7-port1: over-current condition"}
- {"dt": "2023-03-09T05:56:50.013824", "lvl": "target", "msg": "[    3.301181] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T05:56:50.015207", "lvl": "target", "msg": "[    3.307190] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T05:56:50.016435", "lvl": "target", "msg": "[    3.313241] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T05:56:50.017876", "lvl": "target", "msg": "[    3.319234] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T05:56:50.058483", "lvl": "target", "msg": "[    3.325177] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2023-03-09T05:56:50.059867", "lvl": "target", "msg": "[    3.329827] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2023-03-09T05:56:50.062200", "lvl": "target", "msg": "[    3.334689] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2023-03-09T05:56:50.063630", "lvl": "target", "msg": "[    3.341222] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2023-03-09T05:56:50.064849", "lvl": "target", "msg": "[    3.350166] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2023-03-09T05:56:50.066271", "lvl": "target", "msg": "[    3.360325] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2023-03-09T05:56:50.076723", "lvl": "target", "msg": "[    3.385013] random: fast init done"}
- {"dt": "2023-03-09T05:56:50.188146", "lvl": "target", "msg": "[    3.458806] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2023-03-09T05:56:50.189622", "lvl": "target", "msg": "[    3.467754] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2023-03-09T05:56:50.191298", "lvl": "target", "msg": "[    3.477907] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2023-03-09T05:56:50.192903", "lvl": "target", "msg": "[    3.488905] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2023-03-09T05:56:50.304809", "lvl": "target", "msg": "[    3.577903] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2023-03-09T05:56:50.308190", "lvl": "target", "msg": "[    3.582968] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2023-03-09T05:56:50.310110", "lvl": "target", "msg": "[    3.592196] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2023-03-09T05:56:50.312005", "lvl": "target", "msg": "[    3.598272] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2023-03-09T05:56:50.313803", "lvl": "target", "msg": "[    3.604322] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2023-03-09T05:56:50.315459", "lvl": "target", "msg": "[    3.610385] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2023-03-09T05:56:50.316114", "lvl": "target", "msg": "[    3.616429] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2023-03-09T05:56:50.368826", "lvl": "target", "msg": "[    3.622469] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2023-03-09T05:56:50.371332", "lvl": "target", "msg": "[    3.628518] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2023-03-09T05:56:50.373020", "lvl": "target", "msg": "[    3.634561] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2023-03-09T05:56:50.374553", "lvl": "target", "msg": "[    3.643083] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2023-03-09T05:56:50.376204", "lvl": "target", "msg": "[    3.655211] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2023-03-09T05:56:50.377748", "lvl": "target", "msg": "[    3.666446] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2023-03-09T05:56:50.379601", "lvl": "target", "msg": "[    3.677676] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2023-03-09T05:56:50.415154", "lvl": "target", "msg": "[    3.689522] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2023-03-09T05:56:50.416848", "lvl": "target", "msg": "[    3.695681] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2023-03-09T05:56:50.418035", "lvl": "target", "msg": "[    3.700835] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2023-03-09T05:56:50.419043", "lvl": "target", "msg": "[    3.701143] renesas_sdhi_internal_dmac ee140000.mmc: Adding to iommu group 0"}
- {"dt": "2023-03-09T05:56:50.419982", "lvl": "target", "msg": "[    3.708075] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2023-03-09T05:56:50.421071", "lvl": "target", "msg": "[    3.713863] renesas_sdhi_internal_dmac ee140000.mmc: Using IPMMU context 1"}
- {"dt": "2023-03-09T05:56:50.422039", "lvl": "target", "msg": "[    3.727268] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2023-03-09T05:56:50.436338", "lvl": "target", "msg": "[    3.734797] usbcore: registered new interface driver usbhid"}
- {"dt": "2023-03-09T05:56:50.439900", "lvl": "target", "msg": "[    3.740347] usbhid: USB HID core driver"}
- {"dt": "2023-03-09T05:56:50.477775", "lvl": "target", "msg": "[    3.775211] renesas_sdhi_internal_dmac ee140000.mmc: mmc0 base at 0x00000000ee140000, max clock rate 200 MHz"}
- {"dt": "2023-03-09T05:56:50.540833", "lvl": "target", "msg": "[    3.853986] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2023-03-09T05:56:50.590749", "lvl": "target", "msg": "[    3.859728] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2023-03-09T05:56:50.594242", "lvl": "target", "msg": "[    3.864614] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2023-03-09T05:56:50.596246", "lvl": "target", "msg": "[    3.870915] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2023-03-09T05:56:50.598050", "lvl": "target", "msg": "[    3.877024] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (508:0)"}
- {"dt": "2023-03-09T05:56:50.599946", "lvl": "target", "msg": "[    3.885927]  mmcblk0: p1 p2"}
- {"dt": "2023-03-09T05:56:50.601755", "lvl": "target", "msg": "[    3.891823] st_lsm6dsx_i2c 3-006a: mounting matrix not found: using identity..."}
- {"dt": "2023-03-09T05:56:50.603391", "lvl": "target", "msg": "[    3.901937] optee: probing for conduit method."}
- {"dt": "2023-03-09T05:56:50.604052", "lvl": "target", "msg": "[    3.906401] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2023-03-09T05:56:50.617917", "lvl": "target", "msg": "[    3.906843] optee driver R-Car Rev.1.0.11"}
- {"dt": "2023-03-09T05:56:50.619877", "lvl": "target", "msg": "[    3.915263] optee: initialized driver"}
- {"dt": "2023-03-09T05:56:50.621797", "lvl": "target", "msg": "[    3.920237] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2023-03-09T05:56:50.655909", "lvl": "target", "msg": "[    3.938267] rcar_sound ec500000.sound: probed"}
- {"dt": "2023-03-09T05:56:50.658568", "lvl": "target", "msg": "[    3.943189] NET: Registered protocol family 17"}
- {"dt": "2023-03-09T05:56:50.660071", "lvl": "target", "msg": "[    3.947744] 9pnet: Installing 9P2000 support"}
- {"dt": "2023-03-09T05:56:50.661395", "lvl": "target", "msg": "[    3.952058] Key type dns_resolver registered"}
- {"dt": "2023-03-09T05:56:50.662729", "lvl": "target", "msg": "[    3.956678] registered taskstats version 1"}
- {"dt": "2023-03-09T05:56:50.664358", "lvl": "target", "msg": "[    3.960786] Loading compiled-in X.509 certificates"}
- {"dt": "2023-03-09T05:56:50.710959", "lvl": "target", "msg": "[    3.977214] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2023-03-09T05:56:50.712335", "lvl": "target", "msg": "[    3.983116] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2023-03-09T05:56:50.713572", "lvl": "target", "msg": "[    3.988317] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2023-03-09T05:56:50.714936", "lvl": "target", "msg": "[    3.993952] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2023-03-09T05:56:50.716542", "lvl": "target", "msg": "[    4.005564] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2023-03-09T05:56:50.717942", "lvl": "target", "msg": "[    4.013284] [drm] Device feb00000.display probed"}
- {"dt": "2023-03-09T05:56:51.121578", "lvl": "target", "msg": "[    4.398065] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2023-03-09T05:56:51.124027", "lvl": "target", "msg": "[    4.420496] rcar-du feb00000.display: [drm] fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2023-03-09T05:56:51.142705", "lvl": "target", "msg": "[    4.442131] cpu cpu0: EM: created perf domain"}
- {"dt": "2023-03-09T05:56:51.146306", "lvl": "target", "msg": "[    4.446881] cpu cpu4: EM: created perf domain"}
- {"dt": "2023-03-09T05:56:51.205252", "lvl": "target", "msg": "[    4.501269] renesas_sdhi_internal_dmac ee100000.mmc: mmc1 base at 0x00000000ee100000, max clock rate 200 MHz"}
- {"dt": "2023-03-09T05:56:51.289875", "lvl": "target", "msg": "[    4.582891] renesas_sdhi_internal_dmac ee160000.mmc: mmc2 base at 0x00000000ee160000, max clock rate 50 MHz"}
- {"dt": "2023-03-09T05:56:51.382546", "lvl": "target", "msg": "[    4.655583] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: *-skew-ps values should be used only with phy-mode = \\\"rgmii\\\""}
- {"dt": "2023-03-09T05:56:51.386205", "lvl": "target", "msg": "[    4.671444] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=e6800000.ethernet-ffffffff:00, irq=190)"}
- {"dt": "2023-03-09T05:56:58.271127", "lvl": "target", "msg": "[   11.564759] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2023-03-09T05:57:01.175609", "lvl": "target", "msg": "[   11.584901] Sending DHCP requests .., OK"}
- {"dt": "2023-03-09T05:57:01.178350", "lvl": "target", "msg": "[   14.452926] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2023-03-09T05:57:01.179966", "lvl": "target", "msg": "[   14.460721] IP-Config: Complete:"}
- {"dt": "2023-03-09T05:57:01.181321", "lvl": "target", "msg": "[   14.463934]      device=eth0, hwaddr=00:80:45:1b:40:7f, ipaddr=192.168.66.11, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2023-03-09T05:57:01.182674", "lvl": "target", "msg": "[   14.474145]      host=192.168.66.11, domain=, nis-domain=(none)"}
- {"dt": "2023-03-09T05:57:01.184085", "lvl": "target", "msg": "[   14.480035]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2023-03-09T05:57:01.185669", "lvl": "target", "msg": "[   14.480038]      nameserver0=10.1.1.1"}
- {"dt": "2023-03-09T05:57:01.196665", "lvl": "target", "msg": "[   14.490224] ALSA device list:"}
- {"dt": "2023-03-09T05:57:01.198126", "lvl": "target", "msg": "[   14.493188]   #0: ak4613"}
- {"dt": "2023-03-09T05:57:01.200072", "lvl": "target", "msg": "[   14.495943] Waiting 7 sec before mounting root device..."}
- {"dt": "2023-03-09T05:57:08.428503", "lvl": "target", "msg": "[   21.725099] RAMDISK: gzip image found at block 0"}
- {"dt": "2023-03-09T05:57:08.556602", "lvl": "target", "msg": "[   21.830769] EXT4-fs (ram0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-09T05:57:08.560151", "lvl": "target", "msg": "[   21.838466] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2023-03-09T05:57:08.562061", "lvl": "target", "msg": "[   21.844380] devtmpfs: mounted"}
- {"dt": "2023-03-09T05:57:08.563950", "lvl": "target", "msg": "[   21.850596] Freeing unused kernel memory: 7360K"}
- {"dt": "2023-03-09T05:57:08.566031", "lvl": "target", "msg": "[   21.855228] Run /sbin/init as init process"}
- {"dt": "2023-03-09T05:57:08.600592", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T05:57:08.602515", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2023-03-09T05:57:08.604418", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2023-03-09T05:57:08.606532", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2023-03-09T05:57:08.608485", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2023-03-09T05:57:08.610267", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2023-03-09T05:57:08.645662", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T05:57:08.647652", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2023-03-09T05:57:08.648736", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2023-03-09T05:57:08.649699", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2023-03-09T05:57:08.650662", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2023-03-09T05:57:08.651634", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2023-03-09T05:57:08.652432", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2023-03-09T05:57:08.653209", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2023-03-09T05:57:08.653998", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2023-03-09T05:57:08.654785", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2023-03-09T05:57:08.655763", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.66.1:61999"}
- {"dt": "2023-03-09T05:57:08.662145", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2023-03-09T05:57:08.694925", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2023-03-09T05:57:08.735349", "lvl": "target", "msg": "[   22.021787] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-09T05:57:08.736084", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2023-03-09T05:57:08.738375", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2023-03-09T05:57:08.761205", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2023-03-09T05:57:08.761954", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2023-03-09T05:57:08.762538", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2023-03-09T05:57:08.764430", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2023-03-09T05:57:09.153928", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2023-03-09T05:57:09.157512", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0'"}
- {"dt": "2023-03-09T05:57:09.220137", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2023-03-09T05:57:09.300094", "lvl": "target", "msg": "[   22.605640] random: crng init done"}
- {"dt": "2023-03-09T05:57:09.342684", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2023-03-09T05:57:09.346187", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T05:57:09.347624", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2023-03-09T05:57:09.348433", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2023-03-09T05:57:09.349215", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2023-03-09T05:57:09.350012", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2023-03-09T05:57:09.350993", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2023-03-09T05:57:09.359695", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2023-03-09T05:57:10.170374", "lvl": "target", "msg": "[   23.429817] SELinux:  policy capability network_peer_controls=1"}
- {"dt": "2023-03-09T05:57:10.172600", "lvl": "target", "msg": "[   23.435769] SELinux:  policy capability open_perms=1"}
- {"dt": "2023-03-09T05:57:10.174405", "lvl": "target", "msg": "[   23.440714] SELinux:  policy capability extended_socket_class=1"}
- {"dt": "2023-03-09T05:57:10.176287", "lvl": "target", "msg": "[   23.446605] SELinux:  policy capability always_check_network=0"}
- {"dt": "2023-03-09T05:57:10.178079", "lvl": "target", "msg": "[   23.452412] SELinux:  policy capability cgroup_seclabel=1"}
- {"dt": "2023-03-09T05:57:10.179970", "lvl": "target", "msg": "[   23.457783] SELinux:  policy capability nnp_nosuid_transition=1"}
- {"dt": "2023-03-09T05:57:10.182050", "lvl": "target", "msg": "[   23.463677] SELinux:  policy capability genfs_seclabel_symlinks=0"}
- {"dt": "2023-03-09T05:57:10.247855", "lvl": "target", "msg": "[   23.536976] audit: type=1403 audit(23.535:2): auid=4294967295 ses=4294967295 lsm=selinux res=1"}
- {"dt": "2023-03-09T05:57:10.274688", "lvl": "target", "msg": "[   23.564503] systemd[1]: Successfully loaded SELinux policy in 481.332ms."}
- {"dt": "2023-03-09T05:57:10.439696", "lvl": "target", "msg": "[   23.728833] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2023-03-09T05:57:10.627448", "lvl": "target", "msg": "[   23.895769] audit: type=1400 audit(1675100000.163:3): avc:  denied  { confidentiality } for  pid=1 comm=\\\"systemd\\\" lockdown_reason=\\\"use of tracefs\\\" scontext=system_u:system_r:init_t:s0 tcontext=system_u:system_r:init_t:s0 tclass=lockdown permissive=1"}
- {"dt": "2023-03-09T05:57:10.628422", "lvl": "target", "msg": "[   23.896215] NET: Registered protocol family 10"}
- {"dt": "2023-03-09T05:57:10.630373", "lvl": "target", "msg": "[   23.923078] Segment Routing with IPv6"}
- {"dt": "2023-03-09T05:57:10.740351", "lvl": "target", "msg": "[   24.029058] systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 32.958ms."}
- {"dt": "2023-03-09T05:57:10.828088", "lvl": "target", "msg": "[   24.093181] systemd[1]: systemd 250.5+ running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=hybrid)"}
- {"dt": "2023-03-09T05:57:10.831530", "lvl": "target", "msg": "[   24.124821] systemd[1]: Detected architecture arm64."}
- {"dt": "2023-03-09T05:57:10.872994", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:57:10.876135", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 (pike)!"}
- {"dt": "2023-03-09T05:57:10.877182", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:57:10.890993", "lvl": "target", "msg": "[   24.186567] systemd[1]: Hostname set to <h3ulcb>."}
- {"dt": "2023-03-09T05:57:10.924498", "lvl": "target", "msg": "[   24.215362] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2023-03-09T05:57:11.440199", "lvl": "target", "msg": "[   24.729220] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2023-03-09T05:57:11.506191", "lvl": "target", "msg": "[   24.795762] systemd[1]: Created slice Slice /system/agl-session."}
- {"dt": "2023-03-09T05:57:11.509741", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/agl-session."}
- {"dt": "2023-03-09T05:57:11.527183", "lvl": "target", "msg": "[   24.818626] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2023-03-09T05:57:11.530755", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2023-03-09T05:57:11.610957", "lvl": "target", "msg": "[   24.842276] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2023-03-09T05:57:11.611529", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2023-03-09T05:57:11.612075", "lvl": "target", "msg": "[   24.862341] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-09T05:57:11.612538", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-09T05:57:11.612971", "lvl": "target", "msg": "[   24.882141] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-09T05:57:11.613489", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-09T05:57:11.613947", "lvl": "target", "msg": "[   24.901877] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2023-03-09T05:57:11.616289", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2023-03-09T05:57:11.657483", "lvl": "target", "msg": "[   24.921507] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-09T05:57:11.658194", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-09T05:57:11.658684", "lvl": "target", "msg": "[   24.945319] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-09T05:57:11.661982", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-09T05:57:11.715554", "lvl": "target", "msg": "[   24.969099] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-09T05:57:11.716937", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-09T05:57:11.717890", "lvl": "target", "msg": "[   24.988955] systemd[1]: Reached target Path Units."}
- {"dt": "2023-03-09T05:57:11.718694", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2023-03-09T05:57:11.719547", "lvl": "target", "msg": "[   25.004967] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2023-03-09T05:57:11.720412", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2023-03-09T05:57:11.749547", "lvl": "target", "msg": "[   25.024953] systemd[1]: Reached target Slice Units."}
- {"dt": "2023-03-09T05:57:11.750633", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2023-03-09T05:57:11.751716", "lvl": "target", "msg": "[   25.040984] systemd[1]: Reached target Swaps."}
- {"dt": "2023-03-09T05:57:11.753652", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2023-03-09T05:57:11.811759", "lvl": "target", "msg": "[   25.063161] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2023-03-09T05:57:11.813241", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2023-03-09T05:57:11.814589", "lvl": "target", "msg": "[   25.081615] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-09T05:57:11.816010", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-09T05:57:11.817366", "lvl": "target", "msg": "[   25.102580] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2023-03-09T05:57:11.818994", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2023-03-09T05:57:11.851668", "lvl": "target", "msg": "[   25.122768] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-09T05:57:11.853847", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-09T05:57:11.855091", "lvl": "target", "msg": "[   25.141922] systemd[1]: Listening on Journal Socket."}
- {"dt": "2023-03-09T05:57:11.856367", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2023-03-09T05:57:11.872690", "lvl": "target", "msg": "[   25.161972] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-09T05:57:11.876203", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-09T05:57:11.913612", "lvl": "target", "msg": "[   25.183466] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2023-03-09T05:57:11.917202", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2023-03-09T05:57:11.919200", "lvl": "target", "msg": "[   25.201857] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2023-03-09T05:57:11.921029", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2023-03-09T05:57:11.934491", "lvl": "target", "msg": "[   25.222616] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2023-03-09T05:57:11.938127", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2023-03-09T05:57:11.955133", "lvl": "target", "msg": "[   25.245494] systemd[1]: Mounting Huge Pages File System..."}
- {"dt": "2023-03-09T05:57:11.958618", "lvl": "target", "msg": "         Mounting Huge Pages File System..."}
- {"dt": "2023-03-09T05:57:11.983161", "lvl": "target", "msg": "[   25.268782] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-09T05:57:11.983881", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-09T05:57:12.003392", "lvl": "target", "msg": "[   25.292529] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2023-03-09T05:57:12.007054", "lvl": "target", "msg": "         Mounting Kernel Debug File System..."}
- {"dt": "2023-03-09T05:57:12.028031", "lvl": "target", "msg": "[   25.316656] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2023-03-09T05:57:12.029474", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2023-03-09T05:57:12.056819", "lvl": "target", "msg": "[   25.344830] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-09T05:57:12.058275", "lvl": "target", "msg": "         Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-09T05:57:12.084609", "lvl": "target", "msg": "[   25.369043] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-09T05:57:12.087887", "lvl": "target", "msg": "         Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-09T05:57:12.104066", "lvl": "target", "msg": "[   25.392652] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-09T05:57:12.107828", "lvl": "target", "msg": "         Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-09T05:57:12.124978", "lvl": "target", "msg": "[   25.416548] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2023-03-09T05:57:12.128441", "lvl": "target", "msg": "         Starting Load Kernel Module drm..."}
- {"dt": "2023-03-09T05:57:12.148610", "lvl": "target", "msg": "[   25.440471] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-09T05:57:12.151972", "lvl": "target", "msg": "         Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-09T05:57:12.180887", "lvl": "target", "msg": "[   25.465186] systemd[1]: Starting Start psplash boot splash screen..."}
- {"dt": "2023-03-09T05:57:12.183159", "lvl": "target", "msg": "         Starting Start psplash boot splash screen.[   25.474900] fuse: init (API version 7.32)"}
- {"dt": "2023-03-09T05:57:12.184929", "lvl": "target", "msg": ".."}
- {"dt": "2023-03-09T05:57:12.206463", "lvl": "target", "msg": "[   25.493214] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T05:57:12.209877", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T05:57:12.268645", "lvl": "target", "msg": "[   25.517083] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T05:57:12.271343", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T05:57:12.308766", "lvl": "target", "msg": "[   25.577329] systemd[1]: File System Check on Root Device was skipped because of a failed condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2023-03-09T05:57:12.311643", "lvl": "target", "msg": "[   25.596098] systemd[1]: Starting Journal Service..."}
- {"dt": "2023-03-09T05:57:12.313116", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2023-03-09T05:57:12.353419", "lvl": "target", "msg": "[   25.644570] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2023-03-09T05:57:12.356981", "lvl": "target", "msg": "         Starting Load Kernel Modules..."}
- {"dt": "2023-03-09T05:57:12.383688", "lvl": "target", "msg": "[   25.669842] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-09T05:57:12.386857", "lvl": "target", "msg": "         Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-09T05:57:12.436014", "lvl": "target", "msg": "[   25.694126] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-09T05:57:12.439148", "lvl": "target", "msg": "         Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-09T05:57:12.440380", "lvl": "target", "msg": "[   25.713208] EXT4-fs (nbd0): re-mounted. Opts: (null)"}
- {"dt": "2023-03-09T05:57:12.441477", "lvl": "target", "msg": "[   25.717136] audit: type=1400 audit(1675100001.987:4): avc:  denied  { read } for  pid=357 comm=\\\"systemd-journal\\\" name=\\\"machine-id\\\" dev=\\\"nbd0\\\" ino=194 scontext=system_u:system_r:syslogd_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=file permissive=1"}
- {"dt": "2023-03-09T05:57:12.442793", "lvl": "target", "msg": "[   25.727072] systemd[1]: Started Start psplash boot splash screen."}
- {"dt": "2023-03-09T05:57:12.474241", "lvl": "target", "msg": "[   25.740799] audit: type=1400 audit(1675100001.987:5): avc:  denied  { open } for  pid=357 comm=\\\"systemd-journal\\\" path=\\\"/etc/machine-id\\\" dev=\\\"nbd0\\\" ino=194 scontext=system_u:system_r:syslogd_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=file permissive=1"}
- {"dt": "2023-03-09T05:57:12.477258", "lvl": "target", "msg": "[  OK  ] Started Start psplash boot splash screen."}
- {"dt": "2023-03-09T05:57:12.536277", "lvl": "target", "msg": "[   25.785598] audit: type=1130 audit(1675100002.055:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=psplash-start comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.537022", "lvl": "target", "msg": "[   25.786558] systemd[1]: Mounted Huge Pages File System."}
- {"dt": "2023-03-09T05:57:12.537601", "lvl": "target", "msg": "[  OK  ] Mounted Huge Pages File System."}
- {"dt": "2023-03-09T05:57:12.538152", "lvl": "target", "msg": "[   25.825969] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2023-03-09T05:57:12.539434", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2023-03-09T05:57:12.586592", "lvl": "target", "msg": "[   25.845869] systemd[1]: Started Journal Service."}
- {"dt": "2023-03-09T05:57:12.587681", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2023-03-09T05:57:12.589914", "lvl": "target", "msg": "[   25.861385] audit: type=1130 audit(1675100002.131:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-journald comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.591481", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2023-03-09T05:57:12.604942", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2023-03-09T05:57:12.620874", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2023-03-09T05:57:12.637018", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2023-03-09T05:57:12.691373", "lvl": "target", "msg": "[   25.949209] audit: type=1130 audit(1675100002.219:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=kmod-static-nodes comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.693309", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2023-03-09T05:57:12.741437", "lvl": "target", "msg": "[   25.985152] audit: type=1130 audit(1675100002.255:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.744441", "lvl": "target", "msg": "[   26.006845] audit: type=1131 audit(1675100002.255:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.746088", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2023-03-09T05:57:12.769470", "lvl": "target", "msg": "[   26.041147] audit: type=1130 audit(1675100002.311:11): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T05:57:12.772425", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2023-03-09T05:57:12.786551", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T05:57:12.802554", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-09T05:57:12.831340", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2023-03-09T05:57:12.835005", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2023-03-09T05:57:12.849508", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2023-03-09T05:57:12.881440", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2023-03-09T05:57:12.909896", "lvl": "target", "msg": "[  OK  ] Started Start psplash-systemd progress communication helper."}
- {"dt": "2023-03-09T05:57:12.924211", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2023-03-09T05:57:12.940384", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2023-03-09T05:57:12.972560", "lvl": "target", "msg": "[   26.253065] systemd-journald[357]: Received client request to flush runtime journal."}
- {"dt": "2023-03-09T05:57:12.975664", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2023-03-09T05:57:13.002458", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2023-03-09T05:57:13.006039", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2023-03-09T05:57:13.020740", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2023-03-09T05:57:13.036666", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2023-03-09T05:57:13.068756", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2023-03-09T05:57:13.086212", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2023-03-09T05:57:13.196677", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2023-03-09T05:57:13.212690", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2023-03-09T05:57:13.228692", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2023-03-09T05:57:13.244596", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2023-03-09T05:57:13.276482", "lvl": "target", "msg": "         Starting Wait for udev To Complete Device Initialization..."}
- {"dt": "2023-03-09T05:57:13.299738", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2023-03-09T05:57:13.315245", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2023-03-09T05:57:13.330524", "lvl": "target", "msg": "         Starting Load/Save Random Seed..."}
- {"dt": "2023-03-09T05:57:13.346635", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2023-03-09T05:57:13.361917", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2023-03-09T05:57:13.378172", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T05:57:13.393758", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T05:57:13.410376", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2023-03-09T05:57:13.441751", "lvl": "target", "msg": "[  OK  ] Finished Load/Save Random Seed."}
- {"dt": "2023-03-09T05:57:13.458052", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-09T05:57:13.473757", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-09T05:57:13.489963", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2023-03-09T05:57:13.537535", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2023-03-09T05:57:13.554685", "lvl": "target", "msg": "         Starting Security Auditing Service..."}
- {"dt": "2023-03-09T05:57:13.570224", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2023-03-09T05:57:13.992728", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2023-03-09T05:57:14.007726", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2023-03-09T05:57:14.023725", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2023-03-09T05:57:14.120647", "lvl": "target", "msg": "[  OK  ] Started Security Auditing Service."}
- {"dt": "2023-03-09T05:57:14.135207", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2023-03-09T05:57:14.184016", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2023-03-09T05:57:14.375201", "lvl": "target", "msg": "[  OK  ] Reached target Hardware activated USB gadget."}
- {"dt": "2023-03-09T05:57:14.425872", "lvl": "target", "msg": "[   27.709904] at24 7-0050: supply vcc not found, using dummy regulator"}
- {"dt": "2023-03-09T05:57:14.429010", "lvl": "target", "msg": "[   27.718049] at24 7-0050: 128 byte br24t01 EEPROM, writable, 8 bytes/write"}
- {"dt": "2023-03-09T05:57:14.444217", "lvl": "target", "msg": "[   27.743991] CAN device driver interface"}
- {"dt": "2023-03-09T05:57:14.550595", "lvl": "target", "msg": "[   27.813189] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-09T05:57:14.552534", "lvl": "target", "msg": "[   27.820039] rcar_canfd e66c0000.can: device registered (channel 0)"}
- {"dt": "2023-03-09T05:57:14.553386", "lvl": "target", "msg": "[   27.826942] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-09T05:57:14.554281", "lvl": "target", "msg": "[   27.833442] rcar_canfd e66c0000.can: device registered (channel 1)"}
- {"dt": "2023-03-09T05:57:14.556035", "lvl": "target", "msg": "[   27.839761] rcar_canfd e66c0000.can: global operational state (clk 0, fdmode 1)"}
- {"dt": "2023-03-09T05:57:14.605623", "lvl": "target", "msg": "[   27.879110] ccree e6601000.crypto: ARM CryptoCell 630P Driver: HW version 0xAF400001/0xDCC63000, Driver version 5.0"}
- {"dt": "2023-03-09T05:57:14.608532", "lvl": "target", "msg": "[   27.896039] ccree e6601000.crypto: ARM ccree device initialized"}
- {"dt": "2023-03-09T05:57:14.728382", "lvl": "target", "msg": "[   27.993802] Bluetooth: Core ver 2.22"}
- {"dt": "2023-03-09T05:57:14.730512", "lvl": "target", "msg": "[   27.998204] NET: Registered protocol family 31"}
- {"dt": "2023-03-09T05:57:14.732984", "lvl": "target", "msg": "[   28.002687] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2023-03-09T05:57:14.735631", "lvl": "target", "msg": "[   28.011571] Bluetooth: HCI socket layer initialized"}
- {"dt": "2023-03-09T05:57:14.738210", "lvl": "target", "msg": "[   28.019195] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2023-03-09T05:57:14.741761", "lvl": "target", "msg": "[   28.024377] Bluetooth: SCO socket layer initialized"}
- {"dt": "2023-03-09T05:57:14.832093", "lvl": "target", "msg": "[   28.120132] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-09T05:57:14.877032", "lvl": "target", "msg": "[   28.138337] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2023-03-09T05:57:14.877935", "lvl": "target", "msg": "[   28.142832] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2023-03-09T05:57:14.878716", "lvl": "target", "msg": "[   28.147976] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2023-03-09T05:57:14.879569", "lvl": "target", "msg": "[   28.148085] Bluetooth: HCI UART protocol LL registered"}
- {"dt": "2023-03-09T05:57:14.880824", "lvl": "target", "msg": "[  OK  ] Listeni[   28.158834] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2023-03-09T05:57:14.881641", "lvl": "target", "msg": "ng on Load/Save [   28.165554] Bluetooth: HCI UART protocol QCA registered"}
- {"dt": "2023-03-09T05:57:14.883293", "lvl": "target", "msg": "RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2023-03-09T05:57:14.922483", "lvl": "target", "msg": "[   28.195036] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-09T05:57:14.925807", "lvl": "target", "msg": "[   28.204379] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-09T05:57:14.926687", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T05:57:14.971721", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T05:57:14.973567", "lvl": "target", "msg": "[   28.249885] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-09T05:57:14.974462", "lvl": "target", "msg": "[   28.254856] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-09T05:57:14.976825", "lvl": "target", "msg": "[   28.262788] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-09T05:57:14.990287", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T05:57:15.006022", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T05:57:15.037967", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2023-03-09T05:57:15.053701", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-09T05:57:15.110554", "lvl": "target", "msg": "[   28.364983] Bluetooth: hci0: Failed to get CTS"}
- {"dt": "2023-03-09T05:57:15.112655", "lvl": "target", "msg": "[   28.366115] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-09T05:57:15.113638", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T05:57:15.114500", "lvl": "target", "msg": "[   28.381609] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-09T05:57:15.115609", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-09T05:57:15.116508", "lvl": "target", "msg": "[   28.403557] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-09T05:57:15.117331", "lvl": "target", "msg": "[   28.409763] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-09T05:57:15.168800", "lvl": "target", "msg": "[   28.416457] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-09T05:57:15.170642", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-09T05:57:15.171629", "lvl": "target", "msg": "[   28.436605] Registered IR keymap rc-empty"}
- {"dt": "2023-03-09T05:57:15.172441", "lvl": "target", "msg": "[   28.440728] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-09T05:57:15.173290", "lvl": "target", "msg": "[   28.450567] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input0"}
- {"dt": "2023-03-09T05:57:15.174252", "lvl": "target", "msg": "[   28.461324] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-09T05:57:15.201386", "lvl": "target", "msg": "[   28.477942] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-09T05:57:15.202129", "lvl": "target", "msg": "[  OK  ] Finished SELinu[   28.488969] usbcore: registered new interface driver dvb_usb_rtl28xxu"}
- {"dt": "2023-03-09T05:57:15.204583", "lvl": "target", "msg": "x init for /dev service loading."}
- {"dt": "2023-03-09T05:57:15.235385", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T05:57:15.251583", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T05:57:15.266924", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T05:57:15.283167", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T05:57:15.299453", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-09T05:57:15.315012", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T05:57:15.347101", "lvl": "target", "msg": "[  OK  ] Finished Wait for udev To Complete Device Initialization."}
- {"dt": "2023-03-09T05:57:15.361900", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-09T05:57:15.393950", "lvl": "target", "msg": "[  OK  ] Started Hardware RNG Entropy Gatherer Daemon."}
- {"dt": "2023-03-09T05:57:15.425780", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-09T05:57:17.623512", "lvl": "target", "msg": "[*     ] A start job is running for Rebuild \u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2023-03-09T05:57:18.123029", "lvl": "target", "msg": "M[K[**    ] A start job is running for Rebuild \u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2023-03-09T05:57:18.627256", "lvl": "target", "msg": "M[K[***   ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-09T05:57:19.123468", "lvl": "target", "msg": "M[K[ ***  ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-09T05:57:19.623735", "lvl": "target", "msg": "M[K[  *** ] A start job is running for Rebuild \u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2023-03-09T05:57:19.989319", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2023-03-09T05:57:20.021541", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2023-03-09T05:57:20.053486", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2023-03-09T05:57:20.069481", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2023-03-09T05:57:20.085334", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2023-03-09T05:57:20.101302", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2023-03-09T05:57:20.129686", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2023-03-09T05:57:20.144118", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2023-03-09T05:57:20.176238", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2023-03-09T05:57:20.192117", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2023-03-09T05:57:20.224168", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2023-03-09T05:57:20.240520", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2023-03-09T05:57:20.255659", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2023-03-09T05:57:20.287826", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2023-03-09T05:57:20.303863", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2023-03-09T05:57:20.319957", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2023-03-09T05:57:20.335949", "lvl": "target", "msg": "         Starting Save/Restore Sound Card State..."}
- {"dt": "2023-03-09T05:57:20.368058", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2023-03-09T05:57:20.383792", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2023-03-09T05:57:20.416176", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2023-03-09T05:57:20.431547", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2023-03-09T05:57:20.463772", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2023-03-09T05:57:20.479284", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2023-03-09T05:57:20.495243", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2023-03-09T05:57:20.527410", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2023-03-09T05:57:20.559233", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2023-03-09T05:57:20.575408", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager."}
- {"dt": "2023-03-09T05:57:20.607288", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2023-03-09T05:57:20.632301", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2023-03-09T05:57:20.648048", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2023-03-09T05:57:20.679816", "lvl": "target", "msg": "[  OK  ] Finished Save/Restore Sound Card State."}
- {"dt": "2023-03-09T05:57:20.695999", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2023-03-09T05:57:20.711595", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2023-03-09T05:57:20.727626", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2023-03-09T05:57:20.743362", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2023-03-09T05:57:20.775527", "lvl": "target", "msg": "[  OK  ] Reached target Sound Card."}
- {"dt": "2023-03-09T05:57:20.791826", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2023-03-09T05:57:21.661212", "lvl": "target", "msg": "[   34.945756] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2023-03-09T05:57:21.675590", "lvl": "target", "msg": "[   34.965031] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2023-03-09T05:57:21.883871", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2023-03-09T05:57:21.950671", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2023-03-09T05:57:21.954298", "lvl": "target", "msg": "[   35.212265] rcar_canfd e66c0000.can can1: bitrate error 0.1%"}
- {"dt": "2023-03-09T05:57:21.956009", "lvl": "target", "msg": "[   35.223134] rcar_canfd e66c0000.can can0: bitrate error 0.1%"}
- {"dt": "2023-03-09T05:57:21.957500", "lvl": "target", "msg": "         Starting Connection service...[   35.233488] rcar_canfd e66c0000.can can1: incorrect/missing data bit-timing"}
- {"dt": "2023-03-09T05:57:21.959000", "lvl": "target", "msg": "[   35.241565] rcar_canfd e66c0000.can can1: open_candev() failed, error -22"}
- {"dt": "2023-03-09T05:57:21.960731", "lvl": "target", "msg": "[  OK  ] Started[   35.248970] rcar_canfd e66c0000.can can0: incorrect/missing data bit-timing"}
- {"dt": "2023-03-09T05:57:21.971484", "lvl": "target", "msg": " Bluetooth servi[   35.257046] rcar_canfd e66c0000.can can0: open_candev() failed, error -22"}
- {"dt": "2023-03-09T05:57:21.975038", "lvl": "target", "msg": "ce."}
- {"dt": "2023-03-09T05:57:21.989750", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2023-03-09T05:57:22.006091", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2023-03-09T05:57:22.021839", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2023-03-09T05:57:22.077633", "lvl": "target", "msg": "[   35.355929] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2023-03-09T05:57:22.081455", "lvl": "target", "msg": "[   35.361295] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2023-03-09T05:57:22.083147", "lvl": "target", "msg": "[   35.366591] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2023-03-09T05:57:22.159987", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2023-03-09T05:57:22.239587", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2023-03-09T05:57:22.415472", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2023-03-09T05:57:22.431154", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2023-03-09T05:57:22.463421", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2023-03-09T05:57:22.478808", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2023-03-09T05:57:22.511015", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2023-03-09T05:57:22.575118", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val VIS server."}
- {"dt": "2023-03-09T05:57:22.607141", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2023-03-09T05:57:22.639428", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2023-03-09T05:57:22.670621", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2023-03-09T05:57:22.702690", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2023-03-09T05:57:22.734669", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2023-03-09T05:57:22.782409", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2023-03-09T05:57:22.798568", "lvl": "target", "msg": "[  OK  ] Started AGL user session."}
- {"dt": "2023-03-09T05:57:22.830596", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2023-03-09T05:57:22.845773", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttySC0."}
- {"dt": "2023-03-09T05:57:22.878082", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2023-03-09T05:57:22.893879", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2023-03-09T05:57:22.942085", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2023-03-09T05:57:23.692733", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2023-03-09T05:57:23.891084", "lvl": "target", "msg": "[   37.177714] can: controller area network core"}
- {"dt": "2023-03-09T05:57:23.894842", "lvl": "target", "msg": "[   37.182518] NET: Registered protocol family 29"}
- {"dt": "2023-03-09T05:57:23.988894", "lvl": "target", "msg": "[   37.277926] can: raw protocol"}
- {"dt": "2023-03-09T05:57:24.515836", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2023-03-09T05:57:24.532123", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2023-03-09T05:57:24.674434", "lvl": "target", "msg": "[   37.950305] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-09T05:57:24.678828", "lvl": "target", "msg": "[   37.959802] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-09T05:57:24.804318", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2023-03-09T05:57:24.820588", "lvl": "target", "msg": "[  OK  ] Reached target Multi-User System."}
- {"dt": "2023-03-09T05:57:24.835634", "lvl": "target", "msg": "[  OK  ] Reached target Graphical Interface."}
- {"dt": "2023-03-09T05:57:24.852646", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2023-03-09T05:57:24.883775", "lvl": "target", "msg": "         Starting Record Runlevel Change in UTMP..."}
- {"dt": "2023-03-09T05:57:24.916072", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2023-03-09T05:57:24.931697", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-09T05:57:24.996010", "lvl": "target", "msg": "[  OK  ] Finished Record Runlevel Change in UTMP."}
- {"dt": "2023-03-09T05:57:25.235051", "lvl": "target", "msg": "[   38.520303] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-09T05:57:25.266530", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2023-03-09T05:57:25.315315", "lvl": "target", "msg": "[   38.598641] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-09T05:57:25.338080", "lvl": "target", "msg": "[   38.621000] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-09T05:57:25.411247", "lvl": "target", "msg": "[   38.654574] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-09T05:57:25.414237", "lvl": "target", "msg": "[   38.659671] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-09T05:57:25.415896", "lvl": "target", "msg": "[   38.675330] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-09T05:57:25.417405", "lvl": "target", "msg": "[   38.688784] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-09T05:57:25.418459", "lvl": "target", "msg": "[   38.702292] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-09T05:57:25.454254", "lvl": "target", "msg": "[   38.723773] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-09T05:57:25.455813", "lvl": "target", "msg": "[   38.730027] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-09T05:57:25.457421", "lvl": "target", "msg": "[   38.736798] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-09T05:57:25.517583", "lvl": "target", "msg": "[   38.763921] Registered IR keymap rc-empty"}
- {"dt": "2023-03-09T05:57:25.520659", "lvl": "target", "msg": "[   38.768311] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-09T05:57:25.522251", "lvl": "target", "msg": "[   38.780273] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input1"}
- {"dt": "2023-03-09T05:57:25.523870", "lvl": "target", "msg": "[   38.797119] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-09T05:57:25.525364", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2023-03-09T05:57:25.535202", "lvl": "target", "msg": "[   38.816959] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-09T05:57:25.550795", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2023-03-09T05:57:25.695246", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2023-03-09T05:57:25.726705", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2023-03-09T05:57:25.840640", "lvl": "target", "msg": "[   39.118293] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-09T05:57:25.844965", "lvl": "target", "msg": "[   39.125722] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-09T05:57:26.449503", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2023-03-09T05:57:26.481077", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2023-03-09T05:57:28.875566", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:57:28.897206", "lvl": "target", "msg": "Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 h3ulcb ttySC0"}
- {"dt": "2023-03-09T05:57:28.898723", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:57:28.901716", "lvl": "debug", "msg": "Matched prompt #13: login:"}
- {"dt": "2023-03-09T05:57:28.903253", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "41.37", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:57:28.904516", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2023-03-09T05:57:28.905096", "lvl": "input", "msg": "root\n"}
- {"dt": "2023-03-09T05:57:29.006599", "lvl": "target", "msg": "h3ulcb login: root"}
- {"dt": "2023-03-09T05:57:29.008464", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T05:57:29.010139", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:18:40)"}
- {"dt": "2023-03-09T05:57:29.024264", "lvl": "target", "msg": "root"}
- {"dt": "2023-03-09T05:57:32.769286", "lvl": "target", "msg": "7[r[999;999H[6n[   46.044957] SDHI0 Vcc: disabling"}
- {"dt": "2023-03-09T05:57:32.771249", "lvl": "target", "msg": "[   46.048180] SDHI3 Vcc: disabling"}
- {"dt": "2023-03-09T05:57:32.773357", "lvl": "target", "msg": "[   46.051894] wlan-en-regulator: disabling"}
- {"dt": "2023-03-09T05:57:32.774124", "lvl": "target", "msg": "[   46.055961] SDHI0 VccQ: disabling"}
- {"dt": "2023-03-09T05:57:32.774826", "lvl": "target", "msg": "[   46.064904] SDHI3 VccQ: disabling"}
- {"dt": "2023-03-09T05:57:33.346444", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T05:57:33.348962", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:46) [common]"}
- {"dt": "2023-03-09T05:57:33.350835", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "45.82", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:57:33.354809", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:47) [common]"}
- {"dt": "2023-03-09T05:57:33.357162", "lvl": "debug", "msg": "start: 2.4.5 expect-shell-connection (timeout 00:18:36) [common]"}
- {"dt": "2023-03-09T05:57:33.359149", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T05:57:33.359855", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T05:57:33.360433", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-09T05:57:33.411462", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-09T05:57:33.412591", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2023-03-09T05:57:33.413602", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2023-03-09T05:57:33.425298", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T05:57:33.441980", "lvl": "debug", "msg": "end: 2.4.5 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:57:33.444068", "lvl": "debug", "msg": "start: 2.4.6 overlay-unpack (timeout 00:18:36) [common]"}
- {"dt": "2023-03-09T05:57:33.445850", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T05:57:33.548505", "lvl": "target", "msg": "root@h3ulcb:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.569037", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.595491", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2023-03-09T05:57:33.598096", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/32191/compress-overlay-596vfw0o/overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T05:57:33.700978", "lvl": "target", "msg": "root@h3ulcb:~# wget http://192.168.66.1/tmp/32191/compress-overlay-596vfw0o/overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.722459", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/32191/compress-overlay-596vfw0o/overllay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.761214", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2023-03-09T05:57:33.763853", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2023-03-09T05:57:33.765693", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  7670  0:00:00 ETA"}
- {"dt": "2023-03-09T05:57:33.767509", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2023-03-09T05:57:33.769970", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T05:57:33.872432", "lvl": "target", "msg": "root@h3ulcb:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.890128", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T05:57:33.970644", "lvl": "target", "msg": "x lava-32191/"}
- {"dt": "2023-03-09T05:57:33.972619", "lvl": "target", "msg": "x lava-32191/0/"}
- {"dt": "2023-03-09T05:57:34.010477", "lvl": "target", "msg": "x lava-32191/0/lava-test-runner.conf"}
- {"dt": "2023-03-09T05:57:34.011798", "lvl": "target", "msg": "x lava-32191/0/tests/"}
- {"dt": "2023-03-09T05:57:34.013457", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/"}
- {"dt": "2023-03-09T05:57:34.014701", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/README"}
- {"dt": "2023-03-09T05:57:34.015927", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/check_space.sh"}
- {"dt": "2023-03-09T05:57:34.017072", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/check_space.yaml"}
- {"dt": "2023-03-09T05:57:34.018162", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/run.sh"}
- {"dt": "2023-03-09T05:57:34.019348", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/testdef.yaml"}
- {"dt": "2023-03-09T05:57:34.020489", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/testdef_metadata"}
- {"dt": "2023-03-09T05:57:34.021831", "lvl": "target", "msg": "x lava-32191/0/tests/0_check_disk_space/uuid"}
- {"dt": "2023-03-09T05:57:34.023052", "lvl": "target", "msg": "x lava-32191/bin/"}
- {"dt": "2023-03-09T05:57:34.053266", "lvl": "target", "msg": "x lava-32191/bin/lava-add-keys"}
- {"dt": "2023-03-09T05:57:34.054257", "lvl": "target", "msg": "x lava-32191/bin/lava-add-sources"}
- {"dt": "2023-03-09T05:57:34.055558", "lvl": "target", "msg": "x lava-32191/bin/lava-background-process-start"}
- {"dt": "2023-03-09T05:57:34.056394", "lvl": "target", "msg": "x lava-32191/bin/lava-background-process-stop"}
- {"dt": "2023-03-09T05:57:34.057201", "lvl": "target", "msg": "x lava-32191/bin/lava-common-functions"}
- {"dt": "2023-03-09T05:57:34.057961", "lvl": "target", "msg": "x lava-32191/bin/lava-echo-ipv4"}
- {"dt": "2023-03-09T05:57:34.058810", "lvl": "target", "msg": "x lava-32191/bin/lava-install-packages"}
- {"dt": "2023-03-09T05:57:34.059718", "lvl": "target", "msg": "x lava-32191/bin/lava-installed-packages"}
- {"dt": "2023-03-09T05:57:34.060511", "lvl": "target", "msg": "x lava-32191/bin/lava-os-build"}
- {"dt": "2023-03-09T05:57:34.061284", "lvl": "target", "msg": "x lava-32191/bin/lava-probe-channel"}
- {"dt": "2023-03-09T05:57:34.062084", "lvl": "target", "msg": "x lava-32191/bin/lava-probe-ip"}
- {"dt": "2023-03-09T05:57:34.062980", "lvl": "target", "msg": "x lava-32191/bin/lava-target-ip"}
- {"dt": "2023-03-09T05:57:34.063989", "lvl": "target", "msg": "x lava-32191/bin/lava-target-mac"}
- {"dt": "2023-03-09T05:57:34.102521", "lvl": "target", "msg": "x lava-32191/bin/lava-target-storage"}
- {"dt": "2023-03-09T05:57:34.104048", "lvl": "target", "msg": "x lava-32191/bin/lava-test-case"}
- {"dt": "2023-03-09T05:57:34.105357", "lvl": "target", "msg": "x lava-32191/bin/lava-test-event"}
- {"dt": "2023-03-09T05:57:34.106589", "lvl": "target", "msg": "x lava-32191/bin/lava-test-feedback"}
- {"dt": "2023-03-09T05:57:34.108521", "lvl": "target", "msg": "x lava-32191/bin/lava-test-raise"}
- {"dt": "2023-03-09T05:57:34.109892", "lvl": "target", "msg": "x lava-32191/bin/lava-test-reference"}
- {"dt": "2023-03-09T05:57:34.111292", "lvl": "target", "msg": "x lava-32191/bin/lava-test-runner"}
- {"dt": "2023-03-09T05:57:34.112122", "lvl": "target", "msg": "x lava-32191/bin/lava-test-set"}
- {"dt": "2023-03-09T05:57:34.112900", "lvl": "target", "msg": "x lava-32191/bin/lava-test-shell"}
- {"dt": "2023-03-09T05:57:34.113693", "lvl": "target", "msg": "x lava-32191/environment"}
- {"dt": "2023-03-09T05:57:34.114487", "lvl": "target", "msg": "x lava-32191/lava"}
- {"dt": "2023-03-09T05:57:34.115318", "lvl": "target", "msg": "x lava-32191/lava.pub"}
- {"dt": "2023-03-09T05:57:34.115818", "lvl": "target", "msg": "x lava-32191/results/"}
- {"dt": "2023-03-09T05:57:34.116306", "lvl": "target", "msg": "x lava-32191/tests/"}
- {"dt": "2023-03-09T05:57:34.117430", "lvl": "debug", "msg": "end: 2.4.6 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T05:57:34.118301", "lvl": "debug", "msg": "start: 2.4.7 export-device-env (timeout 00:18:35) [common]"}
- {"dt": "2023-03-09T05:57:34.119080", "lvl": "debug", "msg": "end: 2.4.7 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T05:57:34.119819", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:01:22) [common]"}
- {"dt": "2023-03-09T05:57:34.120489", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:01:25) [common]"}
- {"dt": "2023-03-09T05:57:34.121134", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:15:00) [common]"}
- {"dt": "2023-03-09T05:57:34.121759", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:15:00) [common]"}
- {"dt": "2023-03-09T05:57:34.122303", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2023-03-09T05:57:34.122823", "lvl": "input", "msg": "#\n"}
- {"dt": "2023-03-09T05:57:34.224425", "lvl": "target", "msg": "root@h3ulcb:~# #"}
- {"dt": "2023-03-09T05:57:34.226602", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:15:00)"}
- {"dt": "2023-03-09T05:57:34.231768", "lvl": "target", "msg": "#"}
- {"dt": "2023-03-09T05:57:34.248031", "lvl": "debug", "msg": "Using /lava-32191"}
- {"dt": "2023-03-09T05:57:34.249536", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2023-03-09T05:57:34.351969", "lvl": "target", "msg": "root@h3ulcb:~# export SHELL=/bin/sh"}
- {"dt": "2023-03-09T05:57:34.359217", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2023-03-09T05:57:34.375217", "lvl": "input", "msg": ". /lava-32191/environment\n"}
- {"dt": "2023-03-09T05:57:34.477730", "lvl": "target", "msg": "root@h3ulcb:~# . /lava-32191/environment"}
- {"dt": "2023-03-09T05:57:34.486666", "lvl": "target", "msg": ". /lava-32191/environment"}
- {"dt": "2023-03-09T05:57:34.503229", "lvl": "input", "msg": "/lava-32191/bin/lava-test-runner /lava-32191/0\n"}
- {"dt": "2023-03-09T05:57:34.606232", "lvl": "target", "msg": "root@h3ulcb:~# /lava-32191/bin/lava-test-runner /lava-32191/0"}
- {"dt": "2023-03-09T05:57:34.608498", "lvl": "info", "msg": "Test shell timeout: 900s (minimum of the action and connection timeout)"}
- {"dt": "2023-03-09T05:57:34.615726", "lvl": "target", "msg": "/lava-32191/bin/lava-test-runner /lava-32191/0"}
- {"dt": "2023-03-09T05:57:34.761033", "lvl": "target", "msg": "+ export TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-09T05:57:34.762632", "lvl": "target", "msg": "+ TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-09T05:57:34.764083", "lvl": "target", "msg": "+ cd /lava-32191/0/tests/0_check_disk_space"}
- {"dt": "2023-03-09T05:57:34.765340", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2023-03-09T05:57:34.766580", "lvl": "target", "msg": "+ UUID=32191_1.5.4.1"}
- {"dt": "2023-03-09T05:57:34.767904", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-09T05:57:34.769758", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_check_disk_space 32191_1.5.4.1>"}
- {"dt": "2023-03-09T05:57:34.771170", "lvl": "target", "msg": "+ ./check_space.sh"}
- {"dt": "2023-03-09T05:57:34.772005", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_check_disk_space 32191_1.5.4.1"}
- {"dt": "2023-03-09T05:57:34.772552", "lvl": "info", "msg": "Starting test lava.0_check_disk_space (32191_1.5.4.1)"}
- {"dt": "2023-03-09T05:57:34.773121", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2023-03-09T05:57:34.822861", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-09T05:57:34.824792", "lvl": "target", "msg": "/dev/root       6.9M  4.5M  1.9M  71% /boot/initramfs"}
- {"dt": "2023-03-09T05:57:34.826020", "lvl": "target", "msg": "devtmpfs        3.5G     0  3.5G   0% /dev"}
- {"dt": "2023-03-09T05:57:34.827283", "lvl": "target", "msg": "tmpfs           3.8G     0  3.8G   0% /dev/shm"}
- {"dt": "2023-03-09T05:57:34.828385", "lvl": "target", "msg": "/dev/nbd0       3.5G  2.5G  863M  75% /"}
- {"dt": "2023-03-09T05:57:34.829512", "lvl": "target", "msg": "tmpfs           1.6G  9.4M  1.6G   1% /run"}
- {"dt": "2023-03-09T05:57:34.830674", "lvl": "target", "msg": "tmpfs           4.0M     0  4.0M   0% /sys/fs/cgroup"}
- {"dt": "2023-03-09T05:57:34.831532", "lvl": "target", "msg": "tmpfs           3.8G     0  3.8G   0% /tmp"}
- {"dt": "2023-03-09T05:57:34.831965", "lvl": "target", "msg": "tmpfs           3.8G   44K  3.8G   1% /var/volatile"}
- {"dt": "2023-03-09T05:57:34.832529", "lvl": "target", "msg": "tmpfs           777M     0  777M   0% /run/user/1001"}
- {"dt": "2023-03-09T05:57:34.848471", "lvl": "target", "msg": "tmpfs           777M     0  777M   0% /run/user/0"}
- {"dt": "2023-03-09T05:57:34.849528", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-09T05:57:34.850693", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_check_disk_space 32191_1.5.4.1>"}
- {"dt": "2023-03-09T05:57:34.851678", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_check_disk_space 32191_1.5.4.1"}
- {"dt": "2023-03-09T05:57:34.852296", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2023-03-09T05:57:34.852758", "lvl": "info", "msg": "Ending test lava.0_check_disk_space (32191_1.5.4.1), duration 0.08"}
- {"dt": "2023-03-09T05:57:34.853292", "lvl": "results", "msg": {"case": "0_check_disk_space", "commit_id": "404e625f36ab5c06fd87440a3e8e5d327dd1676c", "definition": "lava", "duration": "0.08", "namespace": "common", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "result": "pass", "revision": "unspecified", "uuid": "32191_1.5.4.1"}}
- {"dt": "2023-03-09T05:57:34.854570", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2023-03-09T05:57:34.855487", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2023-03-09T05:57:34.856202", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T05:57:34.856827", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T05:57:34.857478", "lvl": "info", "msg": "start: 4 finalize (timeout 00:30:00) [common]"}
- {"dt": "2023-03-09T05:57:34.858131", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-09T05:57:34.859110", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2023-03-09T05:57:38.276601", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T05:57:38.311438", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-03-09T05:57:38.415804", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-03-09T05:57:38.418027", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.56", "extra": {"status": "success"}, "level": "4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T05:57:38.422424", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-03-09T05:57:38.427308", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-03-09T05:57:39.430530", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-09T05:57:39.432976", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-03-09T05:57:39.435026", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-09T05:57:39.538509", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T05:57:39.540512", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-03-09T05:57:39.542067", "lvl": "debug", "msg": "clean NBD port 61999"}
- {"dt": "2023-03-09T05:57:42.818830", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:08) [common]"}
- {"dt": "2023-03-09T05:57:42.824826", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-09T05:57:42.827300", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/initrd"}
- {"dt": "2023-03-09T05:57:42.829332", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/kernel"}
- {"dt": "2023-03-09T05:57:42.838478", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/dtb"}
- {"dt": "2023-03-09T05:57:42.840064", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32191/nbd-deploy-__pkqsi4/nbdroot"}
- {"dt": "2023-03-09T05:57:43.610376", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32191"}
- {"dt": "2023-03-09T05:57:43.618212", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32191"}
- {"dt": "2023-03-09T05:57:43.619259", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2023-03-09T05:57:43.619962", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
