- {"dt": "2023-03-13T02:57:15.034481", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-13T02:57:15.035842", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-13T02:57:15.036859", "lvl": "info", "msg": "Start time: 2023-03-13 02:57:15.036801+00:00 (UTC)"}
- {"dt": "2023-03-13T02:57:15.037959", "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-13T02:57:15.290027", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-03-13T02:57:15.499241", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb exists"}
- {"dt": "2023-03-13T02:57:15.716055", "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-13T02:57:15.946159", "lvl": "info", "msg": "validate duration: 0.91"}
- {"dt": "2023-03-13T02:57:15.947009", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-13T02:57:15.948190", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-03-13T02:57:15.948848", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-03-13T02:57:15.949478", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-03-13T02:57:15.950042", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-03-13T02:57:15.951105", "lvl": "debug", "msg": "Set_port 61999"}
- {"dt": "2023-03-13T02:57:15.951981", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-03-13T02:57:15.952833", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-03-13T02:57:15.954067", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:57:15.954766", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:57:15.955440", "lvl": "debug", "msg": "total size: 2057818 (1MB)"}
- {"dt": "2023-03-13T02:57:15.956010", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-13T02:57:16.277980", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-03-13T02:57:16.476377", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-03-13T02:57:16.571665", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-03-13T02:57:16.582261", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-03-13T02:57:16.672172", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-03-13T02:57:16.684232", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-03-13T02:57:16.689757", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-03-13T02:57:16.768988", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-03-13T02:57:16.777055", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-03-13T02:57:16.781464", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-03-13T02:57:16.785857", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-03-13T02:57:16.791399", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-03-13T02:57:16.868578", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-03-13T02:57:16.876783", "lvl": "debug", "msg": "progress  70% (1MB)"}
- {"dt": "2023-03-13T02:57:16.882282", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-03-13T02:57:16.886595", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-03-13T02:57:16.892122", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-03-13T02:57:16.961872", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-03-13T02:57:16.970599", "lvl": "debug", "msg": "progress  97% (1MB)"}
- {"dt": "2023-03-13T02:57:16.977447", "lvl": "info", "msg": "1MB downloaded in 1.02s (1.92MB/s)"}
- {"dt": "2023-03-13T02:57:16.978679", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:57:16.979431", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.03", "extra": {"label": "initrd", "md5sum": "28ac0b000066ef9accb6e27c5e4f1541", "sha256sum": "61dbdb553c35c6bcb3933f2b1a4cc3c2991e688fd4a8d56d72673c6a531c7285", "sha512sum": "45e75163a9620ec32113322968d0631d0ddeba121caf320c3423441be5b1b9fe5311a2d4ca9116da5d7032daa62689f61f1578a72d159aacdb02e34cf342673b", "size": ! "2057818"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:57:16.980950", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:57:16.981737", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-03-13T02:57:16.982520", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-03-13T02:57:16.983743", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image"}
- {"dt": "2023-03-13T02:57:16.984437", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/kernel/Image"}
- {"dt": "2023-03-13T02:57:16.984994", "lvl": "debug", "msg": "total size: 39076352 (37MB)"}
- {"dt": "2023-03-13T02:57:16.985537", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-13T02:57:17.332152", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-13T02:57:17.940268", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-13T02:57:18.316767", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-03-13T02:57:18.619946", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-03-13T02:57:19.012691", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-03-13T02:57:19.407739", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-03-13T02:57:19.799861", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-03-13T02:57:20.188136", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-03-13T02:57:20.720990", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-03-13T02:57:21.243340", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-03-13T02:57:21.727917", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-03-13T02:57:22.132404", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-03-13T02:57:22.616088", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-03-13T02:57:23.021959", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-03-13T02:57:23.520121", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-03-13T02:57:23.928072", "lvl": "debug", "msg": "progress  75% (27MB)"}
- {"dt": "2023-03-13T02:57:24.331298", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-03-13T02:57:24.787392", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-03-13T02:57:25.210174", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-03-13T02:57:25.613447", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-03-13T02:57:26.036735", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-03-13T02:57:26.038259", "lvl": "info", "msg": "37MB downloaded in 9.05s (4.12MB/s)"}
- {"dt": "2023-03-13T02:57:26.039450", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:09) [common]"}
- {"dt": "2023-03-13T02:57:26.040129", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "9.06", "extra": {"label": "kernel", "md5sum": "b7ad3f8a8c99f081f6b3a30284fcee3d", "sha256sum": "9192b0042c196dbbc05b4e1ed43cba3d335bb23a9fd5d1c31ba403d944a8b9c7", "sha512sum": "8095cb217fd581f94246357bca8803ee86c04c00798a44e6b04a99774a49b85f8c05273bb46c63a6441ddceec93fda3570883a32b48191aa80d9655bdf75d467", "size": ! "39076352"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:57:26.041609", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:09) [common]"}
- {"dt": "2023-03-13T02:57:26.042404", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:50) [common]"}
- {"dt": "2023-03-13T02:57:26.043242", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:50) [common]"}
- {"dt": "2023-03-13T02:57:26.044356", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:57:26.045038", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:57:26.045593", "lvl": "debug", "msg": "total size: 88958 (0MB)"}
- {"dt": "2023-03-13T02:57:26.046132", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-13T02:57:26.351837", "lvl": "debug", "msg": "progress  36% (0MB)"}
- {"dt": "2023-03-13T02:57:26.449685", "lvl": "debug", "msg": "progress  73% (0MB)"}
- {"dt": "2023-03-13T02:57:26.456544", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-03-13T02:57:26.460071", "lvl": "info", "msg": "0MB downloaded in 0.41s (0.20MB/s)"}
- {"dt": "2023-03-13T02:57:26.461110", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:57:26.461696", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "40d1b427f4f7f206d2f3b2d5730151f2", "sha256sum": "85ae8f72927f8de5e0844d4fb47fef48998e0ffbffb5c02391ce4b52c95290e0", "sha512sum": "eb7e817faa08ae2a831fe53ff0a4a4957b5b5e1ce3a761481c568b7573ef7b9573d2925b7160d0126f6d90dfb1b4007238eb03088c5875453f3ba13b6789bbeb", "size": ! "88958"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:57:26.463113", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:57:26.463790", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:49) [common]"}
- {"dt": "2023-03-13T02:57:26.464416", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:49) [common]"}
- {"dt": "2023-03-13T02:57:26.465355", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-03-13T02:57:26.465966", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-13T02:57:26.466414", "lvl": "debug", "msg": "total size: 486378676 (463MB)"}
- {"dt": "2023-03-13T02:57:26.466851", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-13T02:57:26.798303", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-13T02:57:32.930516", "lvl": "debug", "msg": "progress   5% (23MB)"}
- {"dt": "2023-03-13T02:57:37.629962", "lvl": "debug", "msg": "progress  10% (46MB)"}
- {"dt": "2023-03-13T02:57:43.031446", "lvl": "debug", "msg": "progress  15% (69MB)"}
- {"dt": "2023-03-13T02:57:47.457300", "lvl": "debug", "msg": "progress  20% (92MB)"}
- {"dt": "2023-03-13T02:57:52.404426", "lvl": "debug", "msg": "progress  25% (115MB)"}
- {"dt": "2023-03-13T02:57:57.509003", "lvl": "debug", "msg": "progress  30% (139MB)"}
- {"dt": "2023-03-13T02:58:04.273725", "lvl": "debug", "msg": "progress  35% (162MB)"}
- {"dt": "2023-03-13T02:58:11.218147", "lvl": "debug", "msg": "progress  40% (185MB)"}
- {"dt": "2023-03-13T02:58:16.820744", "lvl": "debug", "msg": "progress  45% (208MB)"}
- {"dt": "2023-03-13T02:58:21.850749", "lvl": "debug", "msg": "progress  50% (231MB)"}
- {"dt": "2023-03-13T02:58:27.071175", "lvl": "debug", "msg": "progress  55% (255MB)"}
- {"dt": "2023-03-13T02:58:33.704223", "lvl": "debug", "msg": "progress  60% (278MB)"}
- {"dt": "2023-03-13T02:58:39.605631", "lvl": "debug", "msg": "progress  65% (301MB)"}
- {"dt": "2023-03-13T02:58:45.227071", "lvl": "debug", "msg": "progress  70% (324MB)"}
- {"dt": "2023-03-13T02:58:49.306506", "lvl": "debug", "msg": "progress  75% (347MB)"}
- {"dt": "2023-03-13T02:58:53.632724", "lvl": "debug", "msg": "progress  80% (371MB)"}
- {"dt": "2023-03-13T02:59:00.063968", "lvl": "debug", "msg": "progress  85% (394MB)"}
- {"dt": "2023-03-13T02:59:07.276073", "lvl": "debug", "msg": "progress  90% (417MB)"}
- {"dt": "2023-03-13T02:59:13.949893", "lvl": "debug", "msg": "progress  95% (440MB)"}
- {"dt": "2023-03-13T02:59:23.044405", "lvl": "debug", "msg": "progress 100% (463MB)"}
- {"dt": "2023-03-13T02:59:26.170435", "lvl": "info", "msg": "463MB downloaded in 119.70s (3.87MB/s)"}
- {"dt": "2023-03-13T02:59:26.172484", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:00) [common]"}
- {"dt": "2023-03-13T02:59:26.173243", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "119.71", "extra": {"label": "nbdroot", "md5sum": "835b516ffbbad322c4bf1e73cb0f2aac", "sha256sum": "87a02b3a62db059a6ad8926d47ee0379756c469d30358ab3f41728a116037658", "sha512sum": "c5e84b43e6e0bfa4db68dbdff03cf757bdfa606175b5fb463634e61995dc13fff8bf2bb9c302341b9a133ae79450d6b7af29feadfeff58a46d4768ebf63e5c0a", "size": ! "486378676"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:59:26.174910", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:00) [common]"}
- {"dt": "2023-03-13T02:59:26.176154", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.178077", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m"}
- {"dt": "2023-03-13T02:59:26.179539", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin"}
- {"dt": "2023-03-13T02:59:26.181075", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/tests"}
- {"dt": "2023-03-13T02:59:26.182417", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/results"}
- {"dt": "2023-03-13T02:59:26.183691", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-add-keys"}
- {"dt": "2023-03-13T02:59:26.185094", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-add-sources"}
- {"dt": "2023-03-13T02:59:26.186488", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-background-process-start"}
- {"dt": "2023-03-13T02:59:26.187925", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-background-process-stop"}
- {"dt": "2023-03-13T02:59:26.189255", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-common-functions"}
- {"dt": "2023-03-13T02:59:26.190573", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-echo-ipv4"}
- {"dt": "2023-03-13T02:59:26.191958", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-install-packages"}
- {"dt": "2023-03-13T02:59:26.193279", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-installed-packages"}
- {"dt": "2023-03-13T02:59:26.194583", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-os-build"}
- {"dt": "2023-03-13T02:59:26.195975", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-probe-channel"}
- {"dt": "2023-03-13T02:59:26.197292", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-probe-ip"}
- {"dt": "2023-03-13T02:59:26.198609", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-target-ip"}
- {"dt": "2023-03-13T02:59:26.200042", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-target-mac"}
- {"dt": "2023-03-13T02:59:26.201376", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-target-storage"}
- {"dt": "2023-03-13T02:59:26.202717", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-case"}
- {"dt": "2023-03-13T02:59:26.204101", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-event"}
- {"dt": "2023-03-13T02:59:26.205453", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-feedback"}
- {"dt": "2023-03-13T02:59:26.206784", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-raise"}
- {"dt": "2023-03-13T02:59:26.208222", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-reference"}
- {"dt": "2023-03-13T02:59:26.209561", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-runner"}
- {"dt": "2023-03-13T02:59:26.210927", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-set"}
- {"dt": "2023-03-13T02:59:26.212291", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-test-shell"}
- {"dt": "2023-03-13T02:59:26.213669", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-13T02:59:26.215281", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-13T02:59:26.216632", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/environment"}
- {"dt": "2023-03-13T02:59:26.217749", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-13T02:59:26.218476", "lvl": "debug", "msg": "- LAVA_JOB_ID=32217"}
- {"dt": "2023-03-13T02:59:26.219149", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-03-13T02:59:26.220201", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.222524", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:26.223529", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.224180", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-13T02:59:26.224873", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:26.225691", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.226299", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-13T02:59:26.227010", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:26.227870", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.228607", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-13T02:59:26.229515", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:50) [common]"}
- {"dt": "2023-03-13T02:59:26.230230", "lvl": "debug", "msg": "Using /lava-32217 at stage 0"}
- {"dt": "2023-03-13T02:59:26.231223", "lvl": "info", "msg": "Fetching tests from https://github.com/duerpei/yan_test.git"}
- {"dt": "2023-03-13T02:59:26.231994", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://github.com/duerpei/yan_test.git /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/0/tests/0_check_disk_space'"}
- {"dt": "2023-03-13T02:59:27.097296", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/0/tests/0_check_disk_space"}
- {"dt": "2023-03-13T02:59:27.115236", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/0/tests/0_check_disk_space/./check_space.yaml"}
- {"dt": "2023-03-13T02:59:27.118198", "lvl": "debug", "msg": "uuid=32217_1.5.4.1 testdef=None"}
- {"dt": "2023-03-13T02:59:27.119267", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:59:27.120013", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.89", "extra": {"commit": "1e1ec669234e47bfb23f24869a10c9df614654f4", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:59:27.121512", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.126777", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.127624", "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": "32217_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:59:27.129173", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.136163", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.136858", "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": "32217_1.5.4.1", "uuid": "32217_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:59:27.138414", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.145023", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/0/tests/0_check_disk_space test_uuid 32217_1.5.4.1"}
- {"dt": "2023-03-13T02:59:27.146551", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.147374", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/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": "32217_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T02:59:27.148791", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-13T02:59:27.149380", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32217/lava-overlay-173_h79m/lava-32217/0 for stage 0"}
- {"dt": "2023-03-13T02:59:27.150340", "lvl": "debug", "msg": "- 0_check_disk_space"}
- {"dt": "2023-03-13T02:59:27.151440", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:59:27.152293", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.201875", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.202829", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.204413", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.205337", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:59:27.206157", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.206994", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.207859", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.208597", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.209416", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:49) [common]"}
- {"dt": "2023-03-13T02:59:27.210083", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-03-13T02:59:27.210722", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61999, NBD-ROOT: 32217/nbd-deploy-319e6xp6/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-13T02:59:27.214285", "lvl": "debug", "msg": "nbd-server 61999 /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-13T02:59:27.237437", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-13T02:59:27.240597", "lvl": "debug", "msg": "output: ** (process:5025): WARNING **: 02:59:27.235: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-03-13T02:59:27.241633", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-13T02:59:27.242637", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-03-13T02:59:27.244892", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.246120", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:11) [common]"}
- {"dt": "2023-03-13T02:59:27.247761", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-03-13T02:59:27.248670", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-03-13T02:59:27.249396", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.250075", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-03-13T02:59:27.251868", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-03-13T02:59:27.252566", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-13T02:59:27.253079", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2023-03-13T02:59:27.253520", "lvl": "debug", "msg": "- {DTB}: 32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:59:27.253952", "lvl": "debug", "msg": "- {INITRD}: 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:59:27.254396", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2023-03-13T02:59:27.254823", "lvl": "debug", "msg": "- {KERNEL}: 32217/nbd-deploy-319e6xp6/kernel/Image"}
- {"dt": "2023-03-13T02:59:27.256197", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-03-13T02:59:27.256694", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-03-13T02:59:27.257132", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61999"}
- {"dt": "2023-03-13T02:59:27.257556", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-03-13T02:59:27.257993", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-03-13T02:59:27.258435", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2023-03-13T02:59:27.258965", "lvl": "debug", "msg": "- {RAMDISK}: 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:59:27.259480", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-03-13T02:59:27.259931", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-03-13T02:59:27.260387", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-03-13T02:59:27.260836", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-03-13T02:59:27.261289", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-03-13T02:59:27.261724", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-03-13T02:59:27.262165", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-03-13T02:59:27.262591", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:27.263086", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:27.263537", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-03-13T02:59:27.263975", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-03-13T02:59:27.264404", "lvl": "info", "msg": "- tftp 0x48080000 32217/nbd-deploy-319e6xp6/kernel/Image"}
- {"dt": "2023-03-13T02:59:27.264837", "lvl": "info", "msg": "- tftp 0x4c000000 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:59:27.265318", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-03-13T02:59:27.265783", "lvl": "info", "msg": "- tftp 0x48000000 32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:59:27.266217", "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-13T02:59:27.266721", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-13T02:59:27.268128", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T02:59:27.268748", "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-13T02:59:27.270173", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-03-13T02:59:27.270752", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2023-03-13T02:59:30.393293", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-03-13T02:59:30.395506", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-13T02:59:30.396676", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-03-13T02:59:30.397815", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-03-13T02:59:30.398834", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-03-13T02:59:30.400481", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2023-03-13T02:59:33.813467", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-13T02:59:34.883468", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-13T02:59:34.920689", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-03-13T02:59:35.024916", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-03-13T02:59:35.027232", "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-13T02:59:35.031437", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-03-13T02:59:35.033890", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-03-13T02:59:35.035590", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-03-13T02:59:35.036226", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-03-13T02:59:35.037941", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-03-13T02:59:35.038655", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-03-13T02:59:35.039277", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-03-13T02:59:35.145431", "lvl": "target", "msg": "\0[    0.000143] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2023-03-13T02:59:35.147311", "lvl": "target", "msg": "[    0.004579] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2023-03-13T02:59:35.148986", "lvl": "target", "msg": "[    0.010112] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-03-13T02:59:35.150492", "lvl": "target", "msg": "[    0.014775] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2023-03-13T02:59:35.152118", "lvl": "target", "msg": "[    0.019547] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2023-03-13T02:59:35.153642", "lvl": "target", "msg": "[    0.024716] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-03-13T02:59:35.155549", "lvl": "target", "msg": "[    0.028756] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-03-13T02:59:35.157085", "lvl": "target", "msg": "[    0.034732] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.214101", "lvl": "target", "msg": "[    0.040594] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.215211", "lvl": "target", "msg": "[    0.046469] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.216339", "lvl": "target", "msg": "[    0.052344] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.217431", "lvl": "target", "msg": "[    0.058261] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2023-03-13T02:59:35.218696", "lvl": "target", "msg": "[    0.073563] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-03-13T02:59:35.219855", "lvl": "target", "msg": "[    0.083126] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-03-13T02:59:35.220916", "lvl": "target", "msg": "[    0.086426] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-03-13T02:59:35.221991", "lvl": "target", "msg": "[    0.091869] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-03-13T02:59:35.223319", "lvl": "target", "msg": "[    0.097226] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-03-13T02:59:35.258140", "lvl": "target", "msg": "[    0.114715] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2023-03-13T02:59:35.260051", "lvl": "target", "msg": "[    0.118359] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2023-03-13T02:59:35.261316", "lvl": "target", "msg": "[    0.123547] NOTICE:  BL2: Normal boot"}
- {"dt": "2023-03-13T02:59:35.262480", "lvl": "target", "msg": "[    0.127182] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2023-03-13T02:59:35.263585", "lvl": "target", "msg": "[    0.133730] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2023-03-13T02:59:35.264502", "lvl": "target", "msg": "[    0.139846] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2023-03-13T02:59:35.265592", "lvl": "target", "msg": "[    0.147446] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2023-03-13T02:59:35.282624", "lvl": "target", "msg": "[    0.156423] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2023-03-13T02:59:35.311638", "lvl": "target", "msg": "[    0.187392] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2023-03-13T02:59:35.326290", "lvl": "target", "msg": "[    0.216098] NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-03-13T02:59:35.564449", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:35.566358", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:35.568142", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2023-03-13T02:59:35.569869", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:35.572440", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-03-13T02:59:35.574253", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-13T02:59:35.934576", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2023-03-13T02:59:35.936635", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2023-03-13T02:59:35.939068", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.940881", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.942569", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-13T02:59:35.944344", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:35.968673", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2023-03-13T02:59:36.299827", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-03-13T02:59:36.301778", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-03-13T02:59:36.303602", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-03-13T02:59:36.306069", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-03-13T02:59:36.349915", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-03-13T02:59:36.352571", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-13T02:59:36.404951", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-03-13T02:59:36.407488", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T02:59:36.409809", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-03-13T02:59:36.411866", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-03-13T02:59:36.413889", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-03-13T02:59:36.429618", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-03-13T02:59:36.431725", "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-13T02:59:36.433060", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-03-13T02:59:36.535109", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-03-13T02:59:36.536884", "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-13T02:59:36.550636", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-03-13T02:59:36.551678", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-03-13T02:59:36.653291", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:36.655513", "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-13T02:59:36.668953", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:36.670435", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-03-13T02:59:36.772542", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:36.774634", "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-13T02:59:36.780730", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-13T02:59:36.783117", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-03-13T02:59:36.884766", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-03-13T02:59:36.886820", "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-13T02:59:36.892421", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-03-13T02:59:42.892889", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-03-13T02:59:42.908847", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-03-13T02:59:43.164237", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-03-13T02:59:43.659207", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-03-13T02:59:44.233446", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1318 ms)"}
- {"dt": "2023-03-13T02:59:44.236064", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-03-13T02:59:44.339028", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-03-13T02:59:44.341134", "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-13T02:59:44.345137", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-03-13T02:59:44.361418", "lvl": "input", "msg": "tftp 0x48080000 32217/nbd-deploy-319e6xp6/kernel/Image\n"}
- {"dt": "2023-03-13T02:59:44.463496", "lvl": "target", "msg": "=> tftp 0x48080000 32217/nbd-deploy-319e6xp6/kernel/Image"}
- {"dt": "2023-03-13T02:59:44.465705", "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-13T02:59:44.472835", "lvl": "target", "msg": "tftp 0x48080000 32217/nbd-deploy-319e6xp6/kernel/Image"}
- {"dt": "2023-03-13T02:59:44.517150", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-13T02:59:44.518576", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-13T02:59:44.520932", "lvl": "target", "msg": "Filename '32217/nbd-deploy-319e6xp6/kernel/Image'."}
- {"dt": "2023-03-13T02:59:44.522441", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2023-03-13T02:59:44.823399", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-03-13T02:59:45.142039", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:45.477482", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:45.797014", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:46.115176", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:46.435020", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:46.754212", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:47.073351", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:47.408427", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:47.727743", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:48.046844", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:48.382053", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:48.701309", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:49.020480", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:49.355406", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:49.674785", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:49.978118", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:50.297236", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:50.616419", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:50.935654", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:51.270627", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:51.589869", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:51.908808", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:52.228205", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:52.547879", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:52.850473", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:53.137447", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:53.425082", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:53.727944", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:54.047823", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:54.366861", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:54.686331", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:55.021278", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:55.340552", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:55.659801", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:55.979001", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:56.314185", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:56.633395", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:56.952534", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:57.255821", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:57.570632", "lvl": "target", "msg": "\t ##############################################################"}
- {"dt": "2023-03-13T02:59:57.572645", "lvl": "target", "msg": "\t 2.9 MiB/s"}
- {"dt": "2023-03-13T02:59:57.574462", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-13T02:59:57.577382", "lvl": "target", "msg": "Bytes transferred = 39076352 (2544200 hex)"}
- {"dt": "2023-03-13T02:59:57.579822", "lvl": "input", "msg": "tftp 0x4c000000 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-03-13T02:59:57.681430", "lvl": "target", "msg": "=> tftp 0x4c000000 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:59:57.683762", "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:30)"}
- {"dt": "2023-03-13T02:59:57.689703", "lvl": "target", "msg": "tftp 0x4c000000 32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-13T02:59:57.726150", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-13T02:59:57.728315", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-13T02:59:57.729805", "lvl": "target", "msg": "Filename '32217/nbd-deploy-319e6xp6/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-03-13T02:59:57.731563", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2023-03-13T02:59:58.063941", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-03-13T02:59:58.383000", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-13T02:59:58.439563", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-03-13T02:59:58.441506", "lvl": "target", "msg": "\t 2.8 MiB/s"}
- {"dt": "2023-03-13T02:59:58.443419", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-13T02:59:58.445987", "lvl": "target", "msg": "Bytes transferred = 2057818 (1f665a hex)"}
- {"dt": "2023-03-13T02:59:58.448664", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-03-13T02:59:58.551486", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-03-13T02:59:58.553752", "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:29)"}
- {"dt": "2023-03-13T02:59:58.569608", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-03-13T02:59:58.572080", "lvl": "input", "msg": "tftp 0x48000000 32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb\n"}
- {"dt": "2023-03-13T02:59:58.674063", "lvl": "target", "msg": "=> tftp 0x48000000 32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:59:58.676385", "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:29)"}
- {"dt": "2023-03-13T02:59:58.718493", "lvl": "target", "msg": "tftp 0x48000000 32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-13T02:59:58.719290", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-13T02:59:58.720343", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-13T02:59:58.720994", "lvl": "target", "msg": "Filename '32217/nbd-deploy-319e6xp6/dtb/r8a77951-agl-refhw.dtb'."}
- {"dt": "2023-03-13T02:59:58.721624", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2023-03-13T02:59:58.763549", "lvl": "target", "msg": "Loading: *\b#######"}
- {"dt": "2023-03-13T02:59:58.764427", "lvl": "target", "msg": "\t 2.3 MiB/s"}
- {"dt": "2023-03-13T02:59:58.765070", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-13T02:59:58.765895", "lvl": "target", "msg": "Bytes transferred = 88958 (15b7e hex)"}
- {"dt": "2023-03-13T02:59:58.767080", "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-13T02:59:58.868881", "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-13T02:59:58.871154", "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:28)"}
- {"dt": "2023-03-13T02:59:58.886654", "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-13T02:59:58.889084", "lvl": "input", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000\n"}
- {"dt": "2023-03-13T02:59:58.991033", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-13T02:59:58.993149", "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-13T02:59:58.995138", "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:28)"}
- {"dt": "2023-03-13T02:59:58.999194", "lvl": "target", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-13T02:59:59.138766", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2023-03-13T02:59:59.140076", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2023-03-13T02:59:59.141313", "lvl": "target", "msg": "   Loading Ramdisk to bfc0e000, end bfe0465a ... OK"}
- {"dt": "2023-03-13T02:59:59.143338", "lvl": "target", "msg": "   Loading Device Tree to 00000000bfbf5000, end 00000000bfc0db7d ... OK"}
- {"dt": "2023-03-13T02:59:59.144584", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:59.145755", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2023-03-13T02:59:59.146977", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T02:59:59.149021", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:23) [common]"}
- {"dt": "2023-03-13T02:59:59.150676", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:28) [common]"}
- {"dt": "2023-03-13T02:59:59.151653", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-13T02:59:59.152165", "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-13T02:59:59.152671", "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-13T03:00:00.445394", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2023-03-13T03:00:00.447806", "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-13T03:00:00.449812", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a77951 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-13T03:00:00.451564", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2023-03-13T03:00:00.453531", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2023-03-13T03:00:00.455921", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:27) [common]"}
- {"dt": "2023-03-13T03:00:00.456588", "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-13T03:00:00.457209", "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-13T03:00:00.457719", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2023-03-13T03:00:00.458182", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2023-03-13T03:00:00.458654", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2023-03-13T03:00:00.459155", "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-13T03:00:00.460101", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:27)"}
- {"dt": "2023-03-13T03:00:00.487759", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-13T03:00:00.488752", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2023-03-13T03:00:00.489748", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-13T03:00:00.490587", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2023-03-13T03:00:00.492585", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-13T03:00:00.530443", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2023-03-13T03:00:00.532355", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-13T03:00:00.533795", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efdfb00-0x77efe1fff]"}
- {"dt": "2023-03-13T03:00:00.535194", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2023-03-13T03:00:00.536602", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2023-03-13T03:00:00.537940", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2023-03-13T03:00:00.539334", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2023-03-13T03:00:00.540675", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2023-03-13T03:00:00.542249", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2023-03-13T03:00:00.573272", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2023-03-13T03:00:00.575247", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000054000000-0x0000000056ffffff]"}
- {"dt": "2023-03-13T03:00:00.576680", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2023-03-13T03:00:00.577976", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2023-03-13T03:00:00.579408", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2023-03-13T03:00:00.580728", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2023-03-13T03:00:00.616227", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-13T03:00:00.617628", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2023-03-13T03:00:00.619988", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2023-03-13T03:00:00.621591", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2023-03-13T03:00:00.623175", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2023-03-13T03:00:00.624694", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2023-03-13T03:00:00.626215", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 33 pages/cpu s94296 r8192 d32680 u135168"}
- {"dt": "2023-03-13T03:00:00.627831", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2023-03-13T03:00:00.629348", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2023-03-13T03:00:00.658993", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v2"}
- {"dt": "2023-03-13T03:00:00.660301", "lvl": "target", "msg": "[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923"}
- {"dt": "2023-03-13T03:00:00.661210", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2032128"}
- {"dt": "2023-03-13T03:00:00.662061", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2023-03-13T03:00:00.664575", "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-13T03:00:00.702669", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2023-03-13T03:00:00.703944", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2023-03-13T03:00:00.705387", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2023-03-13T03:00:00.706621", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000bbbf5000-0x00000000bfbf5000] (64MB)"}
- {"dt": "2023-03-13T03:00:00.745035", "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-13T03:00:00.747633", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2023-03-13T03:00:00.749251", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2023-03-13T03:00:00.750745", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2023-03-13T03:00:00.752389", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2023-03-13T03:00:00.753924", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2023-03-13T03:00:00.755486", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2023-03-13T03:00:00.757053", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2023-03-13T03:00:00.787849", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2023-03-13T03:00:00.789303", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2023-03-13T03:00:00.790265", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2023-03-13T03:00:00.791220", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2023-03-13T03:00:00.792328", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x384/0x548 with crng_init=0"}
- {"dt": "2023-03-13T03:00:00.793252", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2023-03-13T03:00:00.830856", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2023-03-13T03:00:00.833039", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2023-03-13T03:00:00.834319", "lvl": "target", "msg": "[    0.000162] Console: colour dummy device 80x25"}
- {"dt": "2023-03-13T03:00:00.835639", "lvl": "target", "msg": "[    0.000247] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2023-03-13T03:00:00.836835", "lvl": "target", "msg": "[    0.000260] pid_max: default: 32768 minimum: 301"}
- {"dt": "2023-03-13T03:00:00.838024", "lvl": "target", "msg": "[    0.000314] LSM: Security Framework initializing"}
- {"dt": "2023-03-13T03:00:00.839286", "lvl": "target", "msg": "[    0.000341] SELinux:  Initializing."}
- {"dt": "2023-03-13T03:00:00.840691", "lvl": "target", "msg": "[    0.000382] LSM support for eBPF active"}
- {"dt": "2023-03-13T03:00:00.875851", "lvl": "target", "msg": "[    0.000451] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-13T03:00:00.879594", "lvl": "target", "msg": "[    0.000498] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-13T03:00:00.880831", "lvl": "target", "msg": "[    0.002029] rcu: Hierarchical SRCU implementation."}
- {"dt": "2023-03-13T03:00:00.881936", "lvl": "target", "msg": "[    0.003667] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2023-03-13T03:00:00.883090", "lvl": "target", "msg": "[    0.005647] EFI services will not be available."}
- {"dt": "2023-03-13T03:00:00.883752", "lvl": "target", "msg": "[    0.005984] smp: Bringing up secondary CPUs ..."}
- {"dt": "2023-03-13T03:00:00.884262", "lvl": "target", "msg": "[    0.006455] Detected PIPT I-cache on CPU1"}
- {"dt": "2023-03-13T03:00:00.916849", "lvl": "target", "msg": "[    0.006506] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2023-03-13T03:00:00.918115", "lvl": "target", "msg": "[    0.007005] Detected PIPT I-cache on CPU2"}
- {"dt": "2023-03-13T03:00:00.920109", "lvl": "target", "msg": "[    0.007030] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2023-03-13T03:00:00.921112", "lvl": "target", "msg": "[    0.007473] Detected PIPT I-cache on CPU3"}
- {"dt": "2023-03-13T03:00:00.922072", "lvl": "target", "msg": "[    0.007497] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2023-03-13T03:00:00.923101", "lvl": "target", "msg": "[    0.007941] CPU features: detected: ARM erratum 845719"}
- {"dt": "2023-03-13T03:00:00.924030", "lvl": "target", "msg": "[    0.007954] Detected VIPT I-cache on CPU4"}
- {"dt": "2023-03-13T03:00:00.924940", "lvl": "target", "msg": "[    0.008002] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2023-03-13T03:00:00.925862", "lvl": "target", "msg": "[    0.008498] Detected VIPT I-cache on CPU5"}
- {"dt": "2023-03-13T03:00:00.959770", "lvl": "target", "msg": "[    0.008526] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2023-03-13T03:00:00.961910", "lvl": "target", "msg": "[    0.009008] Detected VIPT I-cache on CPU6"}
- {"dt": "2023-03-13T03:00:00.963045", "lvl": "target", "msg": "[    0.009036] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2023-03-13T03:00:00.964009", "lvl": "target", "msg": "[    0.009480] Detected VIPT I-cache on CPU7"}
- {"dt": "2023-03-13T03:00:00.964905", "lvl": "target", "msg": "[    0.009508] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2023-03-13T03:00:00.965898", "lvl": "target", "msg": "[    0.009591] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2023-03-13T03:00:00.966818", "lvl": "target", "msg": "[    0.009619] SMP: Total of 8 processors activated."}
- {"dt": "2023-03-13T03:00:00.967773", "lvl": "target", "msg": "[    0.009625] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2023-03-13T03:00:01.002797", "lvl": "target", "msg": "[    0.009630] CPU features: detected: CRC32 instructions"}
- {"dt": "2023-03-13T03:00:01.005289", "lvl": "target", "msg": "[    0.009635] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2023-03-13T03:00:01.006493", "lvl": "target", "msg": "[    0.022894] CPU: All CPU(s) started at EL2"}
- {"dt": "2023-03-13T03:00:01.007635", "lvl": "target", "msg": "[    0.022935] alternatives: patching kernel code"}
- {"dt": "2023-03-13T03:00:01.008764", "lvl": "target", "msg": "[    0.024023] devtmpfs: initialized"}
- {"dt": "2023-03-13T03:00:01.009858", "lvl": "target", "msg": "[    0.031387] KASLR disabled due to lack of seed"}
- {"dt": "2023-03-13T03:00:01.010964", "lvl": "target", "msg": "[    0.031522] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2023-03-13T03:00:01.012252", "lvl": "target", "msg": "[    0.031536] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.045659", "lvl": "target", "msg": "[    0.040239] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2023-03-13T03:00:01.048265", "lvl": "target", "msg": "[    0.041666] DMI not present or invalid."}
- {"dt": "2023-03-13T03:00:01.049451", "lvl": "target", "msg": "[    0.042040] NET: Registered protocol family 16"}
- {"dt": "2023-03-13T03:00:01.050528", "lvl": "target", "msg": "[    0.042994] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2023-03-13T03:00:01.051751", "lvl": "target", "msg": "[    0.043166] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2023-03-13T03:00:01.052840", "lvl": "target", "msg": "[    0.043455] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2023-03-13T03:00:01.053917", "lvl": "target", "msg": "[    0.043495] audit: initializing netlink subsys (disabled)"}
- {"dt": "2023-03-13T03:00:01.088914", "lvl": "target", "msg": "[    0.043668] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2023-03-13T03:00:01.092420", "lvl": "target", "msg": "[    0.044788] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2023-03-13T03:00:01.094108", "lvl": "target", "msg": "[    0.045311] cpuidle: using governor menu"}
- {"dt": "2023-03-13T03:00:01.095769", "lvl": "target", "msg": "[    0.045481] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2023-03-13T03:00:01.097303", "lvl": "target", "msg": "[    0.045604] ASID allocator initialised with 65536 entries"}
- {"dt": "2023-03-13T03:00:01.098833", "lvl": "target", "msg": "[    0.046834] Serial: AMBA PL011 UART driver"}
- {"dt": "2023-03-13T03:00:01.099715", "lvl": "target", "msg": "[    0.089453] sh-pfc e6060000.pinctrl: r8a77951_pfc support registered"}
- {"dt": "2023-03-13T03:00:01.100355", "lvl": "target", "msg": "[    0.103991] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-13T03:00:01.132459", "lvl": "target", "msg": "[    0.103999] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-13T03:00:01.133598", "lvl": "target", "msg": "[    0.104004] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-13T03:00:01.134542", "lvl": "target", "msg": "[    0.104009] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-13T03:00:01.135701", "lvl": "target", "msg": "[    0.104998] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2023-03-13T03:00:01.136636", "lvl": "target", "msg": "[    0.107233] ACPI: Interpreter disabled."}
- {"dt": "2023-03-13T03:00:01.137586", "lvl": "target", "msg": "[    0.110967] rcar-avs: use avs value: 0"}
- {"dt": "2023-03-13T03:00:01.138498", "lvl": "target", "msg": "[    0.110977] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2023-03-13T03:00:01.139637", "lvl": "target", "msg": "[    0.110983] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2023-03-13T03:00:01.174702", "lvl": "target", "msg": "[    0.110990] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2023-03-13T03:00:01.177406", "lvl": "target", "msg": "[    0.110999] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2023-03-13T03:00:01.178694", "lvl": "target", "msg": "[    0.111074] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2023-03-13T03:00:01.180020", "lvl": "target", "msg": "[    0.112446] iommu: Default domain type: Translated "}
- {"dt": "2023-03-13T03:00:01.181262", "lvl": "target", "msg": "[    0.112988] ipmmu-vmsa e67b0000.iommu: IPMMU context 0 is reserved"}
- {"dt": "2023-03-13T03:00:01.182461", "lvl": "target", "msg": "[    0.113944] vgaarb: loaded"}
- {"dt": "2023-03-13T03:00:01.183716", "lvl": "target", "msg": "[    0.114139] SCSI subsystem initialized"}
- {"dt": "2023-03-13T03:00:01.184896", "lvl": "target", "msg": "[    0.114398] usbcore: registered new interface driver usbfs"}
- {"dt": "2023-03-13T03:00:01.217566", "lvl": "target", "msg": "[    0.114423] usbcore: registered new interface driver hub"}
- {"dt": "2023-03-13T03:00:01.220006", "lvl": "target", "msg": "[    0.114445] usbcore: registered new device driver usb"}
- {"dt": "2023-03-13T03:00:01.221119", "lvl": "target", "msg": "[    0.117850] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2023-03-13T03:00:01.222118", "lvl": "target", "msg": "[    0.118382] mc: Linux media interface: v0.10"}
- {"dt": "2023-03-13T03:00:01.223226", "lvl": "target", "msg": "[    0.118401] videodev: Linux video capture interface: v2.00"}
- {"dt": "2023-03-13T03:00:01.224267", "lvl": "target", "msg": "[    0.118446] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2023-03-13T03:00:01.225255", "lvl": "target", "msg": "[    0.118451] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2023-03-13T03:00:01.226431", "lvl": "target", "msg": "[    0.118460] PTP clock support registered"}
- {"dt": "2023-03-13T03:00:01.260746", "lvl": "target", "msg": "[    0.118617] EDAC MC: Ver: 3.0.0"}
- {"dt": "2023-03-13T03:00:01.262531", "lvl": "target", "msg": "[    0.120444] FPGA manager framework"}
- {"dt": "2023-03-13T03:00:01.266003", "lvl": "target", "msg": "[    0.120495] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2023-03-13T03:00:01.268014", "lvl": "target", "msg": "[    0.121080] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2023-03-13T03:00:01.269775", "lvl": "target", "msg": "[    0.562778] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2023-03-13T03:00:01.271602", "lvl": "target", "msg": "[    0.562824] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2023-03-13T03:00:01.273335", "lvl": "target", "msg": "[    0.562991] pnp: PnP ACPI: disabled"}
- {"dt": "2023-03-13T03:00:01.275050", "lvl": "target", "msg": "[    0.567078] NET: Registered protocol family 2"}
- {"dt": "2023-03-13T03:00:01.275960", "lvl": "target", "msg": "[    0.567467] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.304667", "lvl": "target", "msg": "[    0.569702] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.305886", "lvl": "target", "msg": "[    0.569795] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.306989", "lvl": "target", "msg": "[    0.570074] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.308212", "lvl": "target", "msg": "[    0.570662] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2023-03-13T03:00:01.309295", "lvl": "target", "msg": "[    0.570796] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.346467", "lvl": "target", "msg": "[    0.570906] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-13T03:00:01.348369", "lvl": "target", "msg": "[    0.571130] NET: Registered protocol family 1"}
- {"dt": "2023-03-13T03:00:01.351057", "lvl": "target", "msg": "[    0.571470] RPC: Registered named UNIX socket transport module."}
- {"dt": "2023-03-13T03:00:01.352539", "lvl": "target", "msg": "[    0.571476] RPC: Registered udp transport module."}
- {"dt": "2023-03-13T03:00:01.353888", "lvl": "target", "msg": "[    0.571480] RPC: Registered tcp transport module."}
- {"dt": "2023-03-13T03:00:01.355259", "lvl": "target", "msg": "[    0.571485] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2023-03-13T03:00:01.356612", "lvl": "target", "msg": "[    0.571494] PCI: CLS 0 bytes, default 64"}
- {"dt": "2023-03-13T03:00:01.357709", "lvl": "target", "msg": "[    0.571631] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2023-03-13T03:00:01.358780", "lvl": "target", "msg": "[    0.571808] rootfs image is not initramfs (no cpio magic); looks like an initrd"}
- {"dt": "2023-03-13T03:00:01.389554", "lvl": "target", "msg": "[    0.574773] Freeing initrd memory: 2008K"}
- {"dt": "2023-03-13T03:00:01.392488", "lvl": "target", "msg": "[    0.575663] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2023-03-13T03:00:01.394991", "lvl": "target", "msg": "[    0.575950] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2023-03-13T03:00:01.396053", "lvl": "target", "msg": "[    0.576559] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2023-03-13T03:00:01.396872", "lvl": "target", "msg": "[    0.577447] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2023-03-13T03:00:01.397536", "lvl": "target", "msg": "[    0.577621] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2023-03-13T03:00:01.398216", "lvl": "target", "msg": "[    0.580847] Initialise system trusted keyrings"}
- {"dt": "2023-03-13T03:00:01.432797", "lvl": "target", "msg": "[    0.580998] workingset: timestamp_bits=42 max_order=21 bucket_order=0"}
- {"dt": "2023-03-13T03:00:01.433660", "lvl": "target", "msg": "[    0.584410] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2023-03-13T03:00:01.434428", "lvl": "target", "msg": "[    0.584830] NFS: Registering the id_resolver key type"}
- {"dt": "2023-03-13T03:00:01.435313", "lvl": "target", "msg": "[    0.584849] Key type id_resolver registered"}
- {"dt": "2023-03-13T03:00:01.436539", "lvl": "target", "msg": "[    0.584854] Key type id_legacy registered"}
- {"dt": "2023-03-13T03:00:01.437311", "lvl": "target", "msg": "[    0.584911] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2023-03-13T03:00:01.438029", "lvl": "target", "msg": "[    0.584917] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2023-03-13T03:00:01.440272", "lvl": "target", "msg": "[    0.585095] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2023-03-13T03:00:01.475982", "lvl": "target", "msg": "[    0.611491] NET: Registered protocol family 38"}
- {"dt": "2023-03-13T03:00:01.476752", "lvl": "target", "msg": "[    0.611502] Key type asymmetric registered"}
- {"dt": "2023-03-13T03:00:01.477306", "lvl": "target", "msg": "[    0.611508] Asymmetric key parser 'x509' registered"}
- {"dt": "2023-03-13T03:00:01.477883", "lvl": "target", "msg": "[    0.611537] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2023-03-13T03:00:01.478455", "lvl": "target", "msg": "[    0.611543] io scheduler mq-deadline registered"}
- {"dt": "2023-03-13T03:00:01.479851", "lvl": "target", "msg": "[    0.611548] io scheduler kyber registered"}
- {"dt": "2023-03-13T03:00:01.480580", "lvl": "target", "msg": "[    0.626636] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-13T03:00:01.481300", "lvl": "target", "msg": "[    0.626959] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2023-03-13T03:00:01.484331", "lvl": "target", "msg": "[    0.627244] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2023-03-13T03:00:01.518994", "lvl": "target", "msg": "[    0.627520] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-13T03:00:01.519905", "lvl": "target", "msg": "[    0.627808] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2023-03-13T03:00:01.520715", "lvl": "target", "msg": "[    0.628089] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2023-03-13T03:00:01.521502", "lvl": "target", "msg": "[    0.628260] gpio-369 (wlan-pwr-en): hogged as output/high"}
- {"dt": "2023-03-13T03:00:01.522229", "lvl": "target", "msg": "[    0.628280] gpio-381 (can-1-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-13T03:00:01.523004", "lvl": "target", "msg": "[    0.628297] gpio-372 (can-2-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-13T03:00:01.523737", "lvl": "target", "msg": "[    0.628419] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2023-03-13T03:00:01.525114", "lvl": "target", "msg": "[    0.628693] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2023-03-13T03:00:01.561710", "lvl": "target", "msg": "[    0.630932] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2023-03-13T03:00:01.562487", "lvl": "target", "msg": "[    0.630955] rcar-pcie fe000000.pcie:       IO 0x00fe100000..0x00fe1fffff -> 0x0000000000"}
- {"dt": "2023-03-13T03:00:01.563111", "lvl": "target", "msg": "[    0.630973] rcar-pcie fe000000.pcie:      MEM 0x00fe200000..0x00fe3fffff -> 0x00fe200000"}
- {"dt": "2023-03-13T03:00:01.563785", "lvl": "target", "msg": "[    0.630988] rcar-pcie fe000000.pcie:      MEM 0x0030000000..0x0037ffffff -> 0x0030000000"}
- {"dt": "2023-03-13T03:00:01.564289", "lvl": "target", "msg": "[    0.630998] rcar-pcie fe000000.pcie:      MEM 0x0038000000..0x003fffffff -> 0x0038000000"}
- {"dt": "2023-03-13T03:00:01.604694", "lvl": "target", "msg": "[    0.631011] rcar-pcie fe000000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-13T03:00:01.605537", "lvl": "target", "msg": "[    0.631048] rcar-pcie fe000000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-13T03:00:01.606177", "lvl": "target", "msg": "[    0.631056] rcar-pcie fe000000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-13T03:00:01.607392", "lvl": "target", "msg": "[    0.696724] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2023-03-13T03:00:01.608699", "lvl": "target", "msg": "[    0.696891] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2023-03-13T03:00:01.609488", "lvl": "target", "msg": "[    0.696909] rcar-pcie ee800000.pcie:       IO 0x00ee900000..0x00ee9fffff -> 0x0000000000"}
- {"dt": "2023-03-13T03:00:01.611386", "lvl": "target", "msg": "[    0.696925] rcar-pcie ee800000.pcie:      MEM 0x00eea00000..0x00eebfffff -> 0x00eea00000"}
- {"dt": "2023-03-13T03:00:01.647710", "lvl": "target", "msg": "[    0.696938] rcar-pcie ee800000.pcie:      MEM 0x00c0000000..0x00c7ffffff -> 0x00c0000000"}
- {"dt": "2023-03-13T03:00:01.648541", "lvl": "target", "msg": "[    0.696948] rcar-pcie ee800000.pcie:      MEM 0x00c8000000..0x00cfffffff -> 0x00c8000000"}
- {"dt": "2023-03-13T03:00:01.649269", "lvl": "target", "msg": "[    0.696960] rcar-pcie ee800000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-13T03:00:01.649968", "lvl": "target", "msg": "[    0.696987] rcar-pcie ee800000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-13T03:00:01.650512", "lvl": "target", "msg": "[    0.696994] rcar-pcie ee800000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-13T03:00:01.652674", "lvl": "target", "msg": "[    0.760711] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2023-03-13T03:00:01.653505", "lvl": "target", "msg": "[    0.763021] EINJ: ACPI disabled."}
- {"dt": "2023-03-13T03:00:01.690588", "lvl": "target", "msg": "[    0.794767] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2023-03-13T03:00:01.691570", "lvl": "target", "msg": "[    0.797184] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2023-03-13T03:00:01.692185", "lvl": "target", "msg": "[    0.797543] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 44, base_baud = 0) is a hscif"}
- {"dt": "2023-03-13T03:00:01.692767", "lvl": "target", "msg": "[    0.797718] serial serial0: tty port ttySC4 registered"}
- {"dt": "2023-03-13T03:00:01.693281", "lvl": "target", "msg": "[    0.798142] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 45, base_baud = 0) is a hscif"}
- {"dt": "2023-03-13T03:00:01.695449", "lvl": "target", "msg": "[    0.798674] e6560000.serial: ttySC5 at MMIO 0xe6560000 (irq = 46, base_baud = 0) is a hscif"}
- {"dt": "2023-03-13T03:00:01.741194", "lvl": "target", "msg": "[    0.799248] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 134, base_baud = 0) is a scif"}
- {"dt": "2023-03-13T03:00:01.741999", "lvl": "target", "msg": "[    0.799751] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 135, base_baud = 0) is a scif"}
- {"dt": "2023-03-13T03:00:01.742670", "lvl": "target", "msg": "[    2.101924] printk: console [ttySC0] enabled"}
- {"dt": "2023-03-13T03:00:01.743510", "lvl": "target", "msg": "[    2.106673] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 136, base_baud = 0) is a scif"}
- {"dt": "2023-03-13T03:00:01.745021", "lvl": "target", "msg": "[    2.116145] msm_serial: driver initialized"}
- {"dt": "2023-03-13T03:00:01.746605", "lvl": "target", "msg": "[    2.125884] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-13T03:00:01.778837", "lvl": "target", "msg": "[    2.135743] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-13T03:00:01.779763", "lvl": "target", "msg": "[    2.143909] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-13T03:00:01.780314", "lvl": "target", "msg": "[    2.153710] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-13T03:00:01.782097", "lvl": "target", "msg": "[    2.168500] brd: module loaded"}
- {"dt": "2023-03-13T03:00:01.796957", "lvl": "target", "msg": "[    2.177208] loop: module loaded"}
- {"dt": "2023-03-13T03:00:01.881475", "lvl": "target", "msg": "[    2.227221] megasas: 07.714.04.00-rc1"}
- {"dt": "2023-03-13T03:00:01.882279", "lvl": "target", "msg": "[    2.235985] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2023-03-13T03:00:01.883106", "lvl": "target", "msg": "[    2.241100] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2023-03-13T03:00:01.883874", "lvl": "target", "msg": "[    2.246962] thunder_xcv, ver 1.0"}
- {"dt": "2023-03-13T03:00:01.884613", "lvl": "target", "msg": "[    2.250203] thunder_bgx, ver 1.0"}
- {"dt": "2023-03-13T03:00:01.885351", "lvl": "target", "msg": "[    2.253434] nicpf, ver 1.0"}
- {"dt": "2023-03-13T03:00:01.886105", "lvl": "target", "msg": "[    2.257480] hclge is initializing"}
- {"dt": "2023-03-13T03:00:01.886863", "lvl": "target", "msg": "[    2.260796] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2023-03-13T03:00:01.887625", "lvl": "target", "msg": "[    2.267983] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2023-03-13T03:00:01.889053", "lvl": "target", "msg": "[    2.273295] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-13T03:00:01.927172", "lvl": "target", "msg": "[    2.278149] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2023-03-13T03:00:01.927988", "lvl": "target", "msg": "[    2.283888] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-13T03:00:01.928695", "lvl": "target", "msg": "[    2.288832] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2023-03-13T03:00:01.929390", "lvl": "target", "msg": "[    2.294748] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2023-03-13T03:00:01.930087", "lvl": "target", "msg": "[    2.300120] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2023-03-13T03:00:01.930778", "lvl": "target", "msg": "[    2.305679] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2023-03-13T03:00:01.932158", "lvl": "target", "msg": "[    2.311915] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2023-03-13T03:00:01.932852", "lvl": "target", "msg": "[    2.318193] sky2: driver version 1.30"}
- {"dt": "2023-03-13T03:00:01.980789", "lvl": "target", "msg": "[    2.323108] libphy: ravb_mii: probed"}
- {"dt": "2023-03-13T03:00:01.981644", "lvl": "target", "msg": "[    2.329291] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 129."}
- {"dt": "2023-03-13T03:00:01.982273", "lvl": "target", "msg": "[    2.339421] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2023-03-13T03:00:01.982858", "lvl": "target", "msg": "[    2.346523] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2023-03-13T03:00:01.983535", "lvl": "target", "msg": "[    2.353029] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2023-03-13T03:00:01.984233", "lvl": "target", "msg": "[    2.357470] ehci-platform: EHCI generic platform driver"}
- {"dt": "2023-03-13T03:00:02.023816", "lvl": "target", "msg": "[    2.389147] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.024612", "lvl": "target", "msg": "[    2.394800] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2023-03-13T03:00:02.027232", "lvl": "target", "msg": "[    2.402668] ehci-platform ee080100.usb: irq 182, io mem 0xee080100"}
- {"dt": "2023-03-13T03:00:02.072449", "lvl": "target", "msg": "[    2.421087] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-13T03:00:02.073275", "lvl": "target", "msg": "[    2.427624] hub 1-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.073941", "lvl": "target", "msg": "[    2.431377] hub 1-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.074571", "lvl": "target", "msg": "[    2.435734] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.075938", "lvl": "target", "msg": "[    2.441392] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2023-03-13T03:00:02.077350", "lvl": "target", "msg": "[    2.449249] ehci-platform ee0a0100.usb: irq 183, io mem 0xee0a0100"}
- {"dt": "2023-03-13T03:00:02.121273", "lvl": "target", "msg": "[    2.469086] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-13T03:00:02.122062", "lvl": "target", "msg": "[    2.475512] hub 2-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.122669", "lvl": "target", "msg": "[    2.479266] hub 2-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.123449", "lvl": "target", "msg": "[    2.483572] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.124207", "lvl": "target", "msg": "[    2.489229] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2023-03-13T03:00:02.124833", "lvl": "target", "msg": "[    2.497091] ehci-platform ee0c0100.usb: irq 184, io mem 0xee0c0100"}
- {"dt": "2023-03-13T03:00:02.149224", "lvl": "target", "msg": "[    2.517086] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-13T03:00:02.150081", "lvl": "target", "msg": "[    2.523508] hub 3-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.152138", "lvl": "target", "msg": "[    2.527258] hub 3-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.189687", "lvl": "target", "msg": "[    2.557124] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.190545", "lvl": "target", "msg": "[    2.562768] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2023-03-13T03:00:02.193047", "lvl": "target", "msg": "[    2.570599] ehci-platform ee0e0100.usb: irq 185, io mem 0xee0e0100"}
- {"dt": "2023-03-13T03:00:02.236360", "lvl": "target", "msg": "[    2.589082] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-13T03:00:02.237152", "lvl": "target", "msg": "[    2.595501] hub 4-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.237821", "lvl": "target", "msg": "[    2.599253] hub 4-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.238385", "lvl": "target", "msg": "[    2.603539] ehci-orion: EHCI orion driver"}
- {"dt": "2023-03-13T03:00:02.239571", "lvl": "target", "msg": "[    2.607787] ehci-exynos: EHCI Exynos driver"}
- {"dt": "2023-03-13T03:00:02.240311", "lvl": "target", "msg": "[    2.612079] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2023-03-13T03:00:02.240932", "lvl": "target", "msg": "[    2.618263] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2023-03-13T03:00:02.241551", "lvl": "target", "msg": "[    2.622737] ohci-platform: OHCI generic platform driver"}
- {"dt": "2023-03-13T03:00:02.262439", "lvl": "target", "msg": "[    2.628138] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-13T03:00:02.263299", "lvl": "target", "msg": "[    2.634823] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2023-03-13T03:00:02.265797", "lvl": "target", "msg": "[    2.642666] ohci-platform ee080000.usb: irq 182, io mem 0xee080000"}
- {"dt": "2023-03-13T03:00:02.350339", "lvl": "target", "msg": "[    2.735453] hub 5-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.380247", "lvl": "target", "msg": "[    2.739204] hub 5-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.381067", "lvl": "target", "msg": "[    2.743473] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-13T03:00:02.381861", "lvl": "target", "msg": "[    2.750169] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2023-03-13T03:00:02.383890", "lvl": "target", "msg": "[    2.758029] ohci-platform ee0a0000.usb: irq 183, io mem 0xee0a0000"}
- {"dt": "2023-03-13T03:00:02.496751", "lvl": "target", "msg": "[    2.851441] hub 6-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.497569", "lvl": "target", "msg": "[    2.855190] hub 6-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.498850", "lvl": "target", "msg": "[    2.859446] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-13T03:00:02.499715", "lvl": "target", "msg": "[    2.866142] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2023-03-13T03:00:02.500514", "lvl": "target", "msg": "[    2.873983] ohci-platform ee0c0000.usb: irq 184, io mem 0xee0c0000"}
- {"dt": "2023-03-13T03:00:02.612815", "lvl": "target", "msg": "[    2.967450] hub 7-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.613596", "lvl": "target", "msg": "[    2.971198] hub 7-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.614366", "lvl": "target", "msg": "[    2.975458] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-13T03:00:02.615233", "lvl": "target", "msg": "[    2.982157] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2023-03-13T03:00:02.616762", "lvl": "target", "msg": "[    2.990002] ohci-platform ee0e0000.usb: irq 185, io mem 0xee0e0000"}
- {"dt": "2023-03-13T03:00:02.724370", "lvl": "target", "msg": "[    3.083451] hub 8-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.725137", "lvl": "target", "msg": "[    3.087201] hub 8-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.725759", "lvl": "target", "msg": "[    3.091472] ohci-exynos: OHCI Exynos driver"}
- {"dt": "2023-03-13T03:00:02.726321", "lvl": "target", "msg": "[    3.096328] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.727636", "lvl": "target", "msg": "[    3.101558] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2023-03-13T03:00:02.771363", "lvl": "target", "msg": "[    3.124789] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2023-03-13T03:00:02.772212", "lvl": "target", "msg": "[    3.133912] xhci-hcd ee000000.usb: irq 180, io mem 0xee000000"}
- {"dt": "2023-03-13T03:00:02.772941", "lvl": "target", "msg": "[    3.140063] hub 9-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.774340", "lvl": "target", "msg": "[    3.143819] hub 9-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.775287", "lvl": "target", "msg": "[    3.147881] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-13T03:00:02.776036", "lvl": "target", "msg": "[    3.153091] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2023-03-13T03:00:02.776905", "lvl": "target", "msg": "[    3.160545] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2023-03-13T03:00:02.815328", "lvl": "target", "msg": "[    3.166828] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2023-03-13T03:00:02.817052", "lvl": "target", "msg": "[    3.175235] hub 10-0:1.0: USB hub found"}
- {"dt": "2023-03-13T03:00:02.818667", "lvl": "target", "msg": "[    3.179071] hub 10-0:1.0: 1 port detected"}
- {"dt": "2023-03-13T03:00:02.819638", "lvl": "target", "msg": "[    3.183856] usbcore: registered new interface driver usb-storage"}
- {"dt": "2023-03-13T03:00:02.820388", "lvl": "target", "msg": "[    3.189916] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2023-03-13T03:00:02.821110", "lvl": "target", "msg": "[    3.196424] usbserial: USB Serial support registered for generic"}
- {"dt": "2023-03-13T03:00:02.821913", "lvl": "target", "msg": "[    3.202440] usbcore: registered new interface driver cp210x"}
- {"dt": "2023-03-13T03:00:02.861676", "lvl": "target", "msg": "[    3.207995] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2023-03-13T03:00:02.863213", "lvl": "target", "msg": "[    3.215563] renesas_usbhs e6590000.usb: host probed"}
- {"dt": "2023-03-13T03:00:02.863992", "lvl": "target", "msg": "[    3.220429] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2023-03-13T03:00:02.864696", "lvl": "target", "msg": "[    3.226127] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2023-03-13T03:00:02.865375", "lvl": "target", "msg": "[    3.231311] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2023-03-13T03:00:02.866150", "lvl": "target", "msg": "[    3.236100] renesas_usbhs e659c000.usb: host probed"}
- {"dt": "2023-03-13T03:00:02.866829", "lvl": "target", "msg": "[    3.240968] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2023-03-13T03:00:02.867655", "lvl": "target", "msg": "[    3.246671] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2023-03-13T03:00:02.868504", "lvl": "target", "msg": "[    3.251847] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2023-03-13T03:00:02.884494", "lvl": "target", "msg": "[    3.257332] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2023-03-13T03:00:02.887893", "lvl": "target", "msg": "[    3.264519] i2c /dev entries driver"}
- {"dt": "2023-03-13T03:00:02.931270", "lvl": "target", "msg": "[    3.281153] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2023-03-13T03:00:02.932861", "lvl": "target", "msg": "[    3.285384] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2023-03-13T03:00:02.933703", "lvl": "target", "msg": "[    3.290304] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2023-03-13T03:00:02.934490", "lvl": "target", "msg": "[    3.294329] usb usb7-port1: over-current condition"}
- {"dt": "2023-03-13T03:00:02.935449", "lvl": "target", "msg": "[    3.301363] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-13T03:00:02.936229", "lvl": "target", "msg": "[    3.307381] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-13T03:00:02.936999", "lvl": "target", "msg": "[    3.313433] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-13T03:00:02.937933", "lvl": "target", "msg": "[    3.319431] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-13T03:00:02.983132", "lvl": "target", "msg": "[    3.325377] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2023-03-13T03:00:02.984013", "lvl": "target", "msg": "[    3.330044] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2023-03-13T03:00:02.984809", "lvl": "target", "msg": "[    3.334907] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2023-03-13T03:00:02.985581", "lvl": "target", "msg": "[    3.341447] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2023-03-13T03:00:02.986450", "lvl": "target", "msg": "[    3.350392] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2023-03-13T03:00:02.988105", "lvl": "target", "msg": "[    3.360553] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2023-03-13T03:00:03.000542", "lvl": "target", "msg": "[    3.385243] random: fast init done"}
- {"dt": "2023-03-13T03:00:03.106523", "lvl": "target", "msg": "[    3.459044] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2023-03-13T03:00:03.107475", "lvl": "target", "msg": "[    3.467988] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2023-03-13T03:00:03.108397", "lvl": "target", "msg": "[    3.478145] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2023-03-13T03:00:03.110085", "lvl": "target", "msg": "[    3.481090] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2023-03-13T03:00:03.188106", "lvl": "target", "msg": "[    3.578048] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2023-03-13T03:00:03.234314", "lvl": "target", "msg": "[    3.583106] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2023-03-13T03:00:03.235258", "lvl": "target", "msg": "[    3.592392] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2023-03-13T03:00:03.236052", "lvl": "target", "msg": "[    3.598478] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2023-03-13T03:00:03.236818", "lvl": "target", "msg": "[    3.604534] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2023-03-13T03:00:03.238056", "lvl": "target", "msg": "[    3.610606] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2023-03-13T03:00:03.238817", "lvl": "target", "msg": "[    3.616662] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2023-03-13T03:00:03.241831", "lvl": "target", "msg": "[    3.622715] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2023-03-13T03:00:03.298350", "lvl": "target", "msg": "[    3.628763] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2023-03-13T03:00:03.299175", "lvl": "target", "msg": "[    3.634813] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2023-03-13T03:00:03.300717", "lvl": "target", "msg": "[    3.643347] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2023-03-13T03:00:03.301518", "lvl": "target", "msg": "[    3.655523] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2023-03-13T03:00:03.302191", "lvl": "target", "msg": "[    3.666789] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2023-03-13T03:00:03.302731", "lvl": "target", "msg": "[    3.677900] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2023-03-13T03:00:03.344254", "lvl": "target", "msg": "[    3.689666] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2023-03-13T03:00:03.345175", "lvl": "target", "msg": "[    3.695825] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2023-03-13T03:00:03.345970", "lvl": "target", "msg": "[    3.700959] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2023-03-13T03:00:03.346716", "lvl": "target", "msg": "[    3.701276] renesas_sdhi_internal_dmac ee140000.mmc: Adding to iommu group 0"}
- {"dt": "2023-03-13T03:00:03.347537", "lvl": "target", "msg": "[    3.708217] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2023-03-13T03:00:03.348364", "lvl": "target", "msg": "[    3.713988] renesas_sdhi_internal_dmac ee140000.mmc: Using IPMMU context 1"}
- {"dt": "2023-03-13T03:00:03.349180", "lvl": "target", "msg": "[    3.726717] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2023-03-13T03:00:03.350536", "lvl": "target", "msg": "[    3.734275] usbcore: registered new interface driver usbhid"}
- {"dt": "2023-03-13T03:00:03.362599", "lvl": "target", "msg": "[    3.739825] usbhid: USB HID core driver"}
- {"dt": "2023-03-13T03:00:03.401718", "lvl": "target", "msg": "[    3.774539] renesas_sdhi_internal_dmac ee140000.mmc: mmc0 base at 0x00000000ee140000, max clock rate 200 MHz"}
- {"dt": "2023-03-13T03:00:03.465361", "lvl": "target", "msg": "[    3.854376] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2023-03-13T03:00:03.514841", "lvl": "target", "msg": "[    3.860141] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2023-03-13T03:00:03.515723", "lvl": "target", "msg": "[    3.865033] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2023-03-13T03:00:03.516482", "lvl": "target", "msg": "[    3.871327] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2023-03-13T03:00:03.517218", "lvl": "target", "msg": "[    3.877521] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (508:0)"}
- {"dt": "2023-03-13T03:00:03.517937", "lvl": "target", "msg": "[    3.886424]  mmcblk0: p1 p2"}
- {"dt": "2023-03-13T03:00:03.518657", "lvl": "target", "msg": "[    3.891610] st_lsm6dsx_i2c 3-006a: mounting matrix not found: using identity..."}
- {"dt": "2023-03-13T03:00:03.519720", "lvl": "target", "msg": "[    3.901690] optee: probing for conduit method."}
- {"dt": "2023-03-13T03:00:03.521234", "lvl": "target", "msg": "[    3.906156] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2023-03-13T03:00:03.541905", "lvl": "target", "msg": "[    3.906600] optee driver R-Car Rev.1.0.11"}
- {"dt": "2023-03-13T03:00:03.542742", "lvl": "target", "msg": "[    3.915020] optee: initialized driver"}
- {"dt": "2023-03-13T03:00:03.545324", "lvl": "target", "msg": "[    3.920001] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2023-03-13T03:00:03.580062", "lvl": "target", "msg": "[    3.937796] rcar_sound ec500000.sound: probed"}
- {"dt": "2023-03-13T03:00:03.580877", "lvl": "target", "msg": "[    3.942720] NET: Registered protocol family 17"}
- {"dt": "2023-03-13T03:00:03.581599", "lvl": "target", "msg": "[    3.947266] 9pnet: Installing 9P2000 support"}
- {"dt": "2023-03-13T03:00:03.582359", "lvl": "target", "msg": "[    3.951588] Key type dns_resolver registered"}
- {"dt": "2023-03-13T03:00:03.583204", "lvl": "target", "msg": "[    3.956219] registered taskstats version 1"}
- {"dt": "2023-03-13T03:00:03.584688", "lvl": "target", "msg": "[    3.960331] Loading compiled-in X.509 certificates"}
- {"dt": "2023-03-13T03:00:03.633630", "lvl": "target", "msg": "[    3.976893] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2023-03-13T03:00:03.634556", "lvl": "target", "msg": "[    3.982820] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2023-03-13T03:00:03.636266", "lvl": "target", "msg": "[    3.988024] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2023-03-13T03:00:03.637198", "lvl": "target", "msg": "[    3.993694] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2023-03-13T03:00:03.637910", "lvl": "target", "msg": "[    4.005361] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2023-03-13T03:00:03.638637", "lvl": "target", "msg": "[    4.013076] [drm] Device feb00000.display probed"}
- {"dt": "2023-03-13T03:00:04.045756", "lvl": "target", "msg": "[    4.380728] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2023-03-13T03:00:04.046549", "lvl": "target", "msg": "[    4.403152] rcar-du feb00000.display: [drm] fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2023-03-13T03:00:04.049097", "lvl": "target", "msg": "[    4.417722] cpu cpu0: EM: created perf domain"}
- {"dt": "2023-03-13T03:00:04.049875", "lvl": "target", "msg": "[    4.422482] cpu cpu4: EM: created perf domain"}
- {"dt": "2023-03-13T03:00:04.105804", "lvl": "target", "msg": "[    4.478567] renesas_sdhi_internal_dmac ee100000.mmc: mmc1 base at 0x00000000ee100000, max clock rate 200 MHz"}
- {"dt": "2023-03-13T03:00:04.181595", "lvl": "target", "msg": "[    4.554914] renesas_sdhi_internal_dmac ee160000.mmc: mmc2 base at 0x00000000ee160000, max clock rate 50 MHz"}
- {"dt": "2023-03-13T03:00:04.249426", "lvl": "target", "msg": "[    4.599775] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: *-skew-ps values should be used only with phy-mode = \\\"rgmii\\\""}
- {"dt": "2023-03-13T03:00:04.252759", "lvl": "target", "msg": "[    4.615557] 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-13T03:00:11.029791", "lvl": "target", "msg": "[   11.399516] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2023-03-13T03:00:13.991958", "lvl": "target", "msg": "[   11.421086] Sending DHCP requests .., OK"}
- {"dt": "2023-03-13T03:00:13.995907", "lvl": "target", "msg": "[   14.345002] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2023-03-13T03:00:13.997883", "lvl": "target", "msg": "[   14.352801] IP-Config: Complete:"}
- {"dt": "2023-03-13T03:00:13.999800", "lvl": "target", "msg": "[   14.356017]      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-13T03:00:14.001581", "lvl": "target", "msg": "[   14.366228]      host=192.168.66.11, domain=, nis-domain=(none)"}
- {"dt": "2023-03-13T03:00:14.003253", "lvl": "target", "msg": "[   14.372119]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2023-03-13T03:00:14.003949", "lvl": "target", "msg": "[   14.372123]      nameserver0=10.1.1.1"}
- {"dt": "2023-03-13T03:00:14.011806", "lvl": "target", "msg": "[   14.382343] ALSA device list:"}
- {"dt": "2023-03-13T03:00:14.012510", "lvl": "target", "msg": "[   14.385301]   #0: ak4613"}
- {"dt": "2023-03-13T03:00:14.015376", "lvl": "target", "msg": "[   14.388053] Waiting 7 sec before mounting root device..."}
- {"dt": "2023-03-13T03:00:21.100411", "lvl": "target", "msg": "[   21.469203] RAMDISK: gzip image found at block 0"}
- {"dt": "2023-03-13T03:00:21.228045", "lvl": "target", "msg": "[   21.574683] EXT4-fs (ram0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-13T03:00:21.230038", "lvl": "target", "msg": "[   21.582374] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2023-03-13T03:00:21.232681", "lvl": "target", "msg": "[   21.588306] devtmpfs: mounted"}
- {"dt": "2023-03-13T03:00:21.234471", "lvl": "target", "msg": "[   21.594662] Freeing unused kernel memory: 7360K"}
- {"dt": "2023-03-13T03:00:21.236607", "lvl": "target", "msg": "[   21.599297] Run /sbin/init as init process"}
- {"dt": "2023-03-13T03:00:21.268350", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-13T03:00:21.271440", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2023-03-13T03:00:21.272915", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2023-03-13T03:00:21.274958", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2023-03-13T03:00:21.276313", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2023-03-13T03:00:21.277636", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2023-03-13T03:00:21.313735", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-13T03:00:21.315171", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2023-03-13T03:00:21.315855", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2023-03-13T03:00:21.316471", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2023-03-13T03:00:21.317036", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2023-03-13T03:00:21.317989", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2023-03-13T03:00:21.318981", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2023-03-13T03:00:21.319846", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2023-03-13T03:00:21.320670", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2023-03-13T03:00:21.321504", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2023-03-13T03:00:21.322508", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.66.1:61999"}
- {"dt": "2023-03-13T03:00:21.331202", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2023-03-13T03:00:21.347255", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2023-03-13T03:00:21.388889", "lvl": "target", "msg": "[   21.750226] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-13T03:00:21.389719", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2023-03-13T03:00:21.392485", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2023-03-13T03:00:21.414856", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2023-03-13T03:00:21.415665", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2023-03-13T03:00:21.417113", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2023-03-13T03:00:21.418034", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2023-03-13T03:00:21.811408", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2023-03-13T03:00:21.814815", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0'"}
- {"dt": "2023-03-13T03:00:21.877817", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2023-03-13T03:00:21.941234", "lvl": "target", "msg": "[   22.310012] random: crng init done"}
- {"dt": "2023-03-13T03:00:22.004113", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2023-03-13T03:00:22.005863", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-13T03:00:22.006790", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2023-03-13T03:00:22.007801", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2023-03-13T03:00:22.008633", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2023-03-13T03:00:22.009359", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2023-03-13T03:00:22.010185", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2023-03-13T03:00:22.021670", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2023-03-13T03:00:22.795027", "lvl": "target", "msg": "[   23.130295] SELinux:  policy capability network_peer_controls=1"}
- {"dt": "2023-03-13T03:00:22.796313", "lvl": "target", "msg": "[   23.136212] SELinux:  policy capability open_perms=1"}
- {"dt": "2023-03-13T03:00:22.797602", "lvl": "target", "msg": "[   23.141156] SELinux:  policy capability extended_socket_class=1"}
- {"dt": "2023-03-13T03:00:22.798732", "lvl": "target", "msg": "[   23.147046] SELinux:  policy capability always_check_network=0"}
- {"dt": "2023-03-13T03:00:22.799907", "lvl": "target", "msg": "[   23.152849] SELinux:  policy capability cgroup_seclabel=1"}
- {"dt": "2023-03-13T03:00:22.801011", "lvl": "target", "msg": "[   23.158219] SELinux:  policy capability nnp_nosuid_transition=1"}
- {"dt": "2023-03-13T03:00:22.802284", "lvl": "target", "msg": "[   23.164107] SELinux:  policy capability genfs_seclabel_symlinks=0"}
- {"dt": "2023-03-13T03:00:22.871995", "lvl": "target", "msg": "[   23.237284] audit: type=1403 audit(23.235:2): auid=4294967295 ses=4294967295 lsm=selinux res=1"}
- {"dt": "2023-03-13T03:00:22.899875", "lvl": "target", "msg": "[   23.265300] systemd[1]: Successfully loaded SELinux policy in 447.341ms."}
- {"dt": "2023-03-13T03:00:23.067581", "lvl": "target", "msg": "[   23.432332] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2023-03-13T03:00:23.258010", "lvl": "target", "msg": "[   23.602458] audit: type=1400 audit(1675100000.167: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-13T03:00:23.259715", "lvl": "target", "msg": "[   23.602874] NET: Registered protocol family 10"}
- {"dt": "2023-03-13T03:00:23.261520", "lvl": "target", "msg": "[   23.629830] Segment Routing with IPv6"}
- {"dt": "2023-03-13T03:00:23.368470", "lvl": "target", "msg": "[   23.733255] systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 33.007ms."}
- {"dt": "2023-03-13T03:00:23.457884", "lvl": "target", "msg": "[   23.797476] 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-13T03:00:23.462020", "lvl": "target", "msg": "[   23.829152] systemd[1]: Detected architecture arm64."}
- {"dt": "2023-03-13T03:00:23.489578", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T03:00:23.507989", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 (pike)!"}
- {"dt": "2023-03-13T03:00:23.511910", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T03:00:23.526232", "lvl": "target", "msg": "[   23.895097] systemd[1]: Hostname set to <h3ulcb>."}
- {"dt": "2023-03-13T03:00:23.558663", "lvl": "target", "msg": "[   23.923520] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2023-03-13T03:00:24.080534", "lvl": "target", "msg": "[   24.445425] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2023-03-13T03:00:24.146655", "lvl": "target", "msg": "[   24.511699] systemd[1]: Created slice Slice /system/agl-session."}
- {"dt": "2023-03-13T03:00:24.150355", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/agl-session."}
- {"dt": "2023-03-13T03:00:24.228591", "lvl": "target", "msg": "[   24.534948] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2023-03-13T03:00:24.231933", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2023-03-13T03:00:24.233792", "lvl": "target", "msg": "[   24.554470] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2023-03-13T03:00:24.235592", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2023-03-13T03:00:24.237279", "lvl": "target", "msg": "[   24.574481] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-13T03:00:24.239033", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-13T03:00:24.239647", "lvl": "target", "msg": "[   24.594350] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-13T03:00:24.240173", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-13T03:00:24.295362", "lvl": "target", "msg": "[   24.614062] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2023-03-13T03:00:24.297246", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2023-03-13T03:00:24.298258", "lvl": "target", "msg": "[   24.633665] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-13T03:00:24.299271", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-13T03:00:24.300366", "lvl": "target", "msg": "[   24.657500] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-13T03:00:24.301454", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-13T03:00:24.349216", "lvl": "target", "msg": "[   24.681260] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-13T03:00:24.351877", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-13T03:00:24.353295", "lvl": "target", "msg": "[   24.701143] systemd[1]: Reached target Path Units."}
- {"dt": "2023-03-13T03:00:24.354605", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2023-03-13T03:00:24.355990", "lvl": "target", "msg": "[   24.717153] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2023-03-13T03:00:24.357561", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2023-03-13T03:00:24.383213", "lvl": "target", "msg": "[   24.737153] systemd[1]: Reached target Slice Units."}
- {"dt": "2023-03-13T03:00:24.385381", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2023-03-13T03:00:24.387615", "lvl": "target", "msg": "[   24.753172] systemd[1]: Reached target Swaps."}
- {"dt": "2023-03-13T03:00:24.388767", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2023-03-13T03:00:24.448566", "lvl": "target", "msg": "[   24.775640] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2023-03-13T03:00:24.450436", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2023-03-13T03:00:24.452050", "lvl": "target", "msg": "[   24.793826] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-13T03:00:24.453756", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-13T03:00:24.455581", "lvl": "target", "msg": "[   24.814785] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2023-03-13T03:00:24.457629", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2023-03-13T03:00:24.489900", "lvl": "target", "msg": "[   24.835076] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-13T03:00:24.491133", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-13T03:00:24.492173", "lvl": "target", "msg": "[   24.854105] systemd[1]: Listening on Journal Socket."}
- {"dt": "2023-03-13T03:00:24.493856", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2023-03-13T03:00:24.508230", "lvl": "target", "msg": "[   24.874119] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-13T03:00:24.511624", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-13T03:00:24.549296", "lvl": "target", "msg": "[   24.895643] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2023-03-13T03:00:24.550649", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2023-03-13T03:00:24.553272", "lvl": "target", "msg": "[   24.914024] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2023-03-13T03:00:24.554671", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2023-03-13T03:00:24.570412", "lvl": "target", "msg": "[   24.934870] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2023-03-13T03:00:24.573743", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2023-03-13T03:00:24.591565", "lvl": "target", "msg": "[   24.957652] systemd[1]: Mounting Huge Pages File System..."}
- {"dt": "2023-03-13T03:00:24.595124", "lvl": "target", "msg": "         Mounting Huge Pages File System..."}
- {"dt": "2023-03-13T03:00:24.619581", "lvl": "target", "msg": "[   24.981152] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-13T03:00:24.621205", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-13T03:00:24.639415", "lvl": "target", "msg": "[   25.005040] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2023-03-13T03:00:24.643146", "lvl": "target", "msg": "         Mounting Kernel Debug File System..."}
- {"dt": "2023-03-13T03:00:24.665311", "lvl": "target", "msg": "[   25.029054] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2023-03-13T03:00:24.666727", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2023-03-13T03:00:24.691897", "lvl": "target", "msg": "[   25.055864] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-13T03:00:24.693598", "lvl": "target", "msg": "         Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-13T03:00:24.716836", "lvl": "target", "msg": "[   25.077196] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-13T03:00:24.720379", "lvl": "target", "msg": "         Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-13T03:00:24.736222", "lvl": "target", "msg": "[   25.100834] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-13T03:00:24.739679", "lvl": "target", "msg": "         Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-13T03:00:24.757021", "lvl": "target", "msg": "[   25.124843] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2023-03-13T03:00:24.760624", "lvl": "target", "msg": "         Starting Load Kernel Module drm..."}
- {"dt": "2023-03-13T03:00:24.780654", "lvl": "target", "msg": "[   25.148664] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-13T03:00:24.783901", "lvl": "target", "msg": "         Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-13T03:00:24.813882", "lvl": "target", "msg": "[   25.174924] systemd[1]: Starting Start psplash boot splash screen..."}
- {"dt": "2023-03-13T03:00:24.815087", "lvl": "target", "msg": "         Starting Start psplash boot splash screen..."}
- {"dt": "2023-03-13T03:00:24.816617", "lvl": "target", "msg": "[   25.185498] fuse: init (API version 7.32)"}
- {"dt": "2023-03-13T03:00:24.841087", "lvl": "target", "msg": "[   25.202474] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-13T03:00:24.844048", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-13T03:00:24.913635", "lvl": "target", "msg": "[   25.231743] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-13T03:00:24.915098", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-13T03:00:24.916350", "lvl": "target", "msg": "[   25.261458] systemd[1]: File System Check on Root Device was skipped because of a failed condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2023-03-13T03:00:24.917792", "lvl": "target", "msg": "[   25.277179] systemd[1]: Starting Journal Service..."}
- {"dt": "2023-03-13T03:00:24.919082", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2023-03-13T03:00:24.962657", "lvl": "target", "msg": "[   25.344094] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2023-03-13T03:00:24.978392", "lvl": "target", "msg": "         Starting Load Kernel Modules..."}
- {"dt": "2023-03-13T03:00:25.004348", "lvl": "target", "msg": "[   25.366380] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-13T03:00:25.007672", "lvl": "target", "msg": "         Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-13T03:00:25.064387", "lvl": "target", "msg": "[   25.394503] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-13T03:00:25.066270", "lvl": "target", "msg": "         Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-13T03:00:25.067958", "lvl": "target", "msg": "[   25.413664] EXT4-fs (nbd0): re-mounted. Opts: (null)"}
- {"dt": "2023-03-13T03:00:25.069655", "lvl": "target", "msg": "[   25.423478] systemd[1]: Started Start psplash boot splash screen."}
- {"dt": "2023-03-13T03:00:25.116082", "lvl": "target", "msg": "[   25.427556] audit: type=1400 audit(1675100001.991:4): avc:  denied  { read } for  pid=354 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-13T03:00:25.119084", "lvl": "target", "msg": "[   25.452263] audit: type=1400 audit(1675100001.991:5): avc:  denied  { open } for  pid=354 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-13T03:00:25.120731", "lvl": "target", "msg": "[  OK  ] Started Start psplash boot splash screen."}
- {"dt": "2023-03-13T03:00:25.142788", "lvl": "target", "msg": "[   25.490007] 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-13T03:00:25.146195", "lvl": "target", "msg": "[   25.491812] systemd[1]: Mounted Huge Pages File System."}
- {"dt": "2023-03-13T03:00:25.147659", "lvl": "target", "msg": "[  OK  ] Mounted Huge Pages File System."}
- {"dt": "2023-03-13T03:00:25.202180", "lvl": "target", "msg": "[   25.531570] systemd[1]: Started Journal Service."}
- {"dt": "2023-03-13T03:00:25.205566", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2023-03-13T03:00:25.207464", "lvl": "target", "msg": "[   25.549707] audit: type=1130 audit(1675100002.115: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-13T03:00:25.209474", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2023-03-13T03:00:25.219947", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2023-03-13T03:00:25.220570", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2023-03-13T03:00:25.236290", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2023-03-13T03:00:25.252372", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2023-03-13T03:00:25.291401", "lvl": "target", "msg": "[   25.641357] audit: type=1130 audit(1675100002.207: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-13T03:00:25.295105", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2023-03-13T03:00:25.337334", "lvl": "target", "msg": "[   25.681461] audit: type=1130 audit(1675100002.247: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-13T03:00:25.354108", "lvl": "target", "msg": "[   25.703127] audit: type=1131 audit(1675100002.247: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-13T03:00:25.355249", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2023-03-13T03:00:25.390682", "lvl": "target", "msg": "[   25.741351] audit: type=1130 audit(1675100002.307: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-13T03:00:25.394216", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2023-03-13T03:00:25.407972", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-13T03:00:25.423877", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-13T03:00:25.439811", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2023-03-13T03:00:25.455786", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2023-03-13T03:00:25.471769", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2023-03-13T03:00:25.503732", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2023-03-13T03:00:25.525336", "lvl": "target", "msg": "[  OK  ] Started Start psplash-systemd progress communication helper."}
- {"dt": "2023-03-13T03:00:25.540044", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2023-03-13T03:00:25.556668", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2023-03-13T03:00:25.588064", "lvl": "target", "msg": "[   25.950193] systemd-journald[354]: Received client request to flush runtime journal."}
- {"dt": "2023-03-13T03:00:25.591518", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2023-03-13T03:00:25.619217", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2023-03-13T03:00:25.623005", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2023-03-13T03:00:25.638181", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2023-03-13T03:00:25.653053", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2023-03-13T03:00:25.685142", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2023-03-13T03:00:25.701643", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2023-03-13T03:00:25.796846", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2023-03-13T03:00:25.812856", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2023-03-13T03:00:25.828824", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2023-03-13T03:00:25.860793", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2023-03-13T03:00:25.891171", "lvl": "target", "msg": "         Starting Wait for udev To Complete Device Initialization..."}
- {"dt": "2023-03-13T03:00:25.915881", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2023-03-13T03:00:25.930556", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2023-03-13T03:00:25.946478", "lvl": "target", "msg": "         Starting Load/Save Random Seed..."}
- {"dt": "2023-03-13T03:00:25.962944", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2023-03-13T03:00:25.978677", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2023-03-13T03:00:26.010451", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-13T03:00:26.027096", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-13T03:00:26.057855", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2023-03-13T03:00:26.086620", "lvl": "target", "msg": "[  OK  ] Finished Load/Save Random Seed."}
- {"dt": "2023-03-13T03:00:26.089744", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-13T03:00:26.105608", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-13T03:00:26.120977", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2023-03-13T03:00:26.153655", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2023-03-13T03:00:26.185574", "lvl": "target", "msg": "         Starting Security Auditing Service..."}
- {"dt": "2023-03-13T03:00:26.200783", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2023-03-13T03:00:26.696027", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2023-03-13T03:00:26.711989", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2023-03-13T03:00:26.727983", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2023-03-13T03:00:26.823918", "lvl": "target", "msg": "[  OK  ] Started Security Auditing Service."}
- {"dt": "2023-03-13T03:00:26.856009", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2023-03-13T03:00:26.903303", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2023-03-13T03:00:26.978436", "lvl": "target", "msg": "[   27.320631] at24 7-0050: supply vcc not found, using dummy regulator"}
- {"dt": "2023-03-13T03:00:26.980290", "lvl": "target", "msg": "[   27.332399] at24 7-0050: 128 byte br24t01 EEPROM, writable, 8 bytes/write"}
- {"dt": "2023-03-13T03:00:26.982173", "lvl": "target", "msg": "[  OK  ] Reached target Hardware activated USB gadget."}
- {"dt": "2023-03-13T03:00:27.011617", "lvl": "target", "msg": "[   27.390388] CAN device driver interface"}
- {"dt": "2023-03-13T03:00:27.102241", "lvl": "target", "msg": "[   27.443506] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-13T03:00:27.105582", "lvl": "target", "msg": "[   27.450786] rcar_canfd e66c0000.can: device registered (channel 0)"}
- {"dt": "2023-03-13T03:00:27.107361", "lvl": "target", "msg": "[   27.457097] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-13T03:00:27.108952", "lvl": "target", "msg": "[   27.463184] rcar_canfd e66c0000.can: device registered (channel 1)"}
- {"dt": "2023-03-13T03:00:27.110763", "lvl": "target", "msg": "[   27.469372] rcar_canfd e66c0000.can: global operational state (clk 0, fdmode 1)"}
- {"dt": "2023-03-13T03:00:27.178548", "lvl": "target", "msg": "[   27.524024] ccree e6601000.crypto: ARM CryptoCell 630P Driver: HW version 0xAF400001/0xDCC63000, Driver version 5.0"}
- {"dt": "2023-03-13T03:00:27.181262", "lvl": "target", "msg": "[   27.543802] ccree e6601000.crypto: ARM ccree device initialized"}
- {"dt": "2023-03-13T03:00:27.276040", "lvl": "target", "msg": "[   27.652727] Bluetooth: Core ver 2.22"}
- {"dt": "2023-03-13T03:00:27.291976", "lvl": "target", "msg": "[   27.671932] NET: Registered protocol family 31"}
- {"dt": "2023-03-13T03:00:27.323748", "lvl": "target", "msg": "[   27.677587] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2023-03-13T03:00:27.325603", "lvl": "target", "msg": "[   27.684481] Bluetooth: HCI socket layer initialized"}
- {"dt": "2023-03-13T03:00:27.327210", "lvl": "target", "msg": "[   27.689432] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2023-03-13T03:00:27.328137", "lvl": "target", "msg": "[   27.694923] Bluetooth: SCO socket layer initialized"}
- {"dt": "2023-03-13T03:00:27.458576", "lvl": "target", "msg": "[   27.797389] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2023-03-13T03:00:27.460071", "lvl": "target", "msg": "[   27.801967] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2023-03-13T03:00:27.461392", "lvl": "target", "msg": "[   27.807163] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2023-03-13T03:00:27.462700", "lvl": "target", "msg": "[   27.812642] Bluetooth: HCI UART protocol LL registered"}
- {"dt": "2023-03-13T03:00:27.464054", "lvl": "target", "msg": "[  OK  ] Listeni[   27.818399] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2023-03-13T03:00:27.466593", "lvl": "target", "msg": "ng on Load/Save [   27.825187] Bluetooth: HCI UART protocol QCA registered"}
- {"dt": "2023-03-13T03:00:27.468053", "lvl": "target", "msg": "RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2023-03-13T03:00:27.525751", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-13T03:00:27.528133", "lvl": "target", "msg": "[   27.876225] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-13T03:00:27.529717", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-13T03:00:27.544050", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-13T03:00:27.559923", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-13T03:00:27.594463", "lvl": "target", "msg": "[   27.950817] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-13T03:00:27.598158", "lvl": "target", "msg": "[   27.960178] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-13T03:00:27.613139", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2023-03-13T03:00:27.628909", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-13T03:00:27.687927", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-13T03:00:27.691347", "lvl": "target", "msg": "[   28.026886] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-13T03:00:27.692995", "lvl": "target", "msg": "[   28.030593] Bluetooth: hci0: Failed to get CTS"}
- {"dt": "2023-03-13T03:00:27.694527", "lvl": "target", "msg": "[   28.031888] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-13T03:00:27.696460", "lvl": "target", "msg": "[   28.044157] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-13T03:00:27.698052", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-13T03:00:27.706379", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-13T03:00:27.745589", "lvl": "target", "msg": "[  OK  ] Finished SELinu[   28.101757] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-13T03:00:27.746981", "lvl": "target", "msg": "x init for /dev service loading."}
- {"dt": "2023-03-13T03:00:27.748462", "lvl": "target", "msg": "[   28.115126] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-13T03:00:27.810744", "lvl": "target", "msg": "[   28.148797] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-13T03:00:27.812584", "lvl": "target", "msg": "[   28.155051] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-13T03:00:27.814151", "lvl": "target", "msg": "[   28.161800] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-13T03:00:27.815430", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-13T03:00:27.816128", "lvl": "target", "msg": "[   28.181495] Registered IR keymap rc-empty"}
- {"dt": "2023-03-13T03:00:27.816958", "lvl": "target", "msg": "[   28.185634] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-13T03:00:27.868351", "lvl": "target", "msg": "[   28.195464] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input0"}
- {"dt": "2023-03-13T03:00:27.870055", "lvl": "target", "msg": "[   28.206138] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-13T03:00:27.871727", "lvl": "target", "msg": "[   28.222589] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-13T03:00:27.873475", "lvl": "target", "msg": "         Starting SELinu[   28.233307] usbcore: registered new interface driver dvb_usb_rtl28xxu"}
- {"dt": "2023-03-13T03:00:27.875023", "lvl": "target", "msg": "x autorelabel service loading..."}
- {"dt": "2023-03-13T03:00:27.884944", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-13T03:00:27.901580", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-13T03:00:27.945903", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-13T03:00:27.949292", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-13T03:00:27.964438", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-13T03:00:28.011978", "lvl": "target", "msg": "[  OK  ] Finished Wait for udev To Complete Device Initialization."}
- {"dt": "2023-03-13T03:00:28.044022", "lvl": "target", "msg": "[  OK  ] Started Hardware RNG Entropy Gatherer Daemon."}
- {"dt": "2023-03-13T03:00:28.075924", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-13T03:00:30.342962", "lvl": "target", "msg": "[*     ] A start job is running for Rebuild \u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2023-03-13T03:00:30.801517", "lvl": "target", "msg": "M[K[**    ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-13T03:00:31.385225", "lvl": "target", "msg": "M[K[***   ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-13T03:00:31.798610", "lvl": "target", "msg": "M[K[ ***  ] A start job is running for Rebuild \u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2023-03-13T03:00:32.385444", "lvl": "target", "msg": "M[K[  *** ] A start job is running for Rebuild \u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2023-03-13T03:00:32.544733", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2023-03-13T03:00:32.575589", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2023-03-13T03:00:32.607692", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2023-03-13T03:00:32.623759", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2023-03-13T03:00:32.639814", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2023-03-13T03:00:32.655769", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2023-03-13T03:00:32.687656", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2023-03-13T03:00:32.703571", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2023-03-13T03:00:32.735474", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2023-03-13T03:00:32.751400", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2023-03-13T03:00:32.783474", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2023-03-13T03:00:32.799064", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2023-03-13T03:00:32.815034", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2023-03-13T03:00:32.847127", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2023-03-13T03:00:32.863210", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2023-03-13T03:00:32.879233", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2023-03-13T03:00:32.895213", "lvl": "target", "msg": "         Starting Save/Restore Sound Card State..."}
- {"dt": "2023-03-13T03:00:32.927109", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2023-03-13T03:00:32.959374", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2023-03-13T03:00:32.974929", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2023-03-13T03:00:32.990403", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2023-03-13T03:00:33.022310", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2023-03-13T03:00:33.038279", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2023-03-13T03:00:33.054217", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2023-03-13T03:00:33.086283", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2023-03-13T03:00:33.102533", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2023-03-13T03:00:33.118256", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager."}
- {"dt": "2023-03-13T03:00:33.156238", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2023-03-13T03:00:33.180369", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2023-03-13T03:00:33.195365", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2023-03-13T03:00:33.226932", "lvl": "target", "msg": "[  OK  ] Finished Save/Restore Sound Card State."}
- {"dt": "2023-03-13T03:00:33.243928", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2023-03-13T03:00:33.258759", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2023-03-13T03:00:33.274966", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2023-03-13T03:00:33.292063", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2023-03-13T03:00:33.307611", "lvl": "target", "msg": "[  OK  ] Reached target Sound Card."}
- {"dt": "2023-03-13T03:00:33.322775", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2023-03-13T03:00:34.121170", "lvl": "target", "msg": "[   34.485571] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2023-03-13T03:00:34.143447", "lvl": "target", "msg": "[   34.504605] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2023-03-13T03:00:34.477314", "lvl": "target", "msg": "[  OK  ] Started Network Configu[   34.814029] rcar_canfd e66c0000.can can1: bitrate error 0.1%"}
- {"dt": "2023-03-13T03:00:34.479178", "lvl": "target", "msg": "ration."}
- {"dt": "2023-03-13T03:00:34.482849", "lvl": "target", "msg": "[   34.822608] rcar_canfd e66c0000.can can0: bitrate error 0.1%"}
- {"dt": "2023-03-13T03:00:34.484682", "lvl": "target", "msg": "[   34.832393] rcar_canfd e66c0000.can can1: incorrect/missing data bit-timing"}
- {"dt": "2023-03-13T03:00:34.486350", "lvl": "target", "msg": "[   34.839544] rcar_canfd e66c0000.can can1: open_candev() failed, error -22"}
- {"dt": "2023-03-13T03:00:34.487481", "lvl": "target", "msg": "[   34.846908] rcar_canfd e66c0000.can can0: incorrect/missing data bit-timing"}
- {"dt": "2023-03-13T03:00:34.488326", "lvl": "target", "msg": "[   34.853912] rcar_canfd e66c0000.can can0: open_candev() failed, error -22"}
- {"dt": "2023-03-13T03:00:34.495066", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2023-03-13T03:00:34.527806", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2023-03-13T03:00:34.558735", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2023-03-13T03:00:34.574207", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2023-03-13T03:00:34.590208", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2023-03-13T03:00:34.607497", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2023-03-13T03:00:34.736917", "lvl": "target", "msg": "[   35.095636] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2023-03-13T03:00:34.738290", "lvl": "target", "msg": "[   35.100982] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2023-03-13T03:00:34.741334", "lvl": "target", "msg": "[   35.108278] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2023-03-13T03:00:34.836305", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2023-03-13T03:00:34.866774", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2023-03-13T03:00:34.946951", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2023-03-13T03:00:34.994754", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2023-03-13T03:00:35.010655", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2023-03-13T03:00:35.042265", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2023-03-13T03:00:35.058595", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2023-03-13T03:00:35.122252", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val VIS server."}
- {"dt": "2023-03-13T03:00:35.138191", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2023-03-13T03:00:35.170070", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2023-03-13T03:00:35.218665", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2023-03-13T03:00:35.234719", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2023-03-13T03:00:35.250003", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2023-03-13T03:00:35.329681", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2023-03-13T03:00:35.345448", "lvl": "target", "msg": "[  OK  ] Started AGL user session."}
- {"dt": "2023-03-13T03:00:35.377605", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2023-03-13T03:00:35.425678", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttySC0."}
- {"dt": "2023-03-13T03:00:35.441358", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2023-03-13T03:00:35.459902", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2023-03-13T03:00:35.601048", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2023-03-13T03:00:36.335770", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2023-03-13T03:00:36.393422", "lvl": "target", "msg": "[   36.756443] can: controller area network core"}
- {"dt": "2023-03-13T03:00:36.397163", "lvl": "target", "msg": "[   36.761031] NET: Registered protocol family 29"}
- {"dt": "2023-03-13T03:00:36.475327", "lvl": "target", "msg": "[   36.842099] can: raw protocol"}
- {"dt": "2023-03-13T03:00:37.082556", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2023-03-13T03:00:37.130466", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2023-03-13T03:00:37.210524", "lvl": "target", "msg": "[   37.586557] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-13T03:00:37.238400", "lvl": "target", "msg": "[   37.598928] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-13T03:00:37.392695", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2023-03-13T03:00:37.395991", "lvl": "target", "msg": "[  OK  ] Reached target Multi-User System."}
- {"dt": "2023-03-13T03:00:37.411075", "lvl": "target", "msg": "[  OK  ] Reached target Graphical Interface."}
- {"dt": "2023-03-13T03:00:37.427014", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2023-03-13T03:00:37.459112", "lvl": "target", "msg": "         Starting Record Runlevel Change in UTMP..."}
- {"dt": "2023-03-13T03:00:37.491031", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2023-03-13T03:00:37.523066", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-13T03:00:37.618820", "lvl": "target", "msg": "[  OK  ] Finished Record Runlevel Change in UTMP."}
- {"dt": "2023-03-13T03:00:37.809664", "lvl": "target", "msg": "[   38.159461] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-13T03:00:37.813662", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2023-03-13T03:00:37.901518", "lvl": "target", "msg": "[   38.249900] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-13T03:00:37.906808", "lvl": "target", "msg": "[   38.261670] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-13T03:00:37.931233", "lvl": "target", "msg": "[   38.288449] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-13T03:00:37.933144", "lvl": "target", "msg": "[   38.293526] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-13T03:00:38.007157", "lvl": "target", "msg": "[   38.314600] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-13T03:00:38.010318", "lvl": "target", "msg": "[   38.326071] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-13T03:00:38.012101", "lvl": "target", "msg": "[   38.338400] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-13T03:00:38.013739", "lvl": "target", "msg": "[   38.352417] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-13T03:00:38.015184", "lvl": "target", "msg": "[   38.358703] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-13T03:00:38.016711", "lvl": "target", "msg": "[   38.369209] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-13T03:00:38.058232", "lvl": "target", "msg": "[   38.395226] Registered IR keymap rc-empty"}
- {"dt": "2023-03-13T03:00:38.059916", "lvl": "target", "msg": "[   38.399638] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-13T03:00:38.062811", "lvl": "target", "msg": "[   38.410571] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input1"}
- {"dt": "2023-03-13T03:00:38.064823", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2023-03-13T03:00:38.087738", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2023-03-13T03:00:38.091573", "lvl": "target", "msg": "[   38.450092] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-13T03:00:38.112475", "lvl": "target", "msg": "[   38.469222] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-13T03:00:38.318652", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2023-03-13T03:00:38.369560", "lvl": "target", "msg": "[   38.714552] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-13T03:00:38.371324", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2023-03-13T03:00:38.374990", "lvl": "target", "msg": "[   38.728931] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-13T03:00:39.121297", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2023-03-13T03:00:39.137097", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2023-03-13T03:00:41.420033", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T03:00:41.441697", "lvl": "target", "msg": "Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 h3ulcb ttySC0"}
- {"dt": "2023-03-13T03:00:41.442707", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T03:00:41.445165", "lvl": "debug", "msg": "Matched prompt #13: login:"}
- {"dt": "2023-03-13T03:00:41.446121", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "40.99", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T03:00:41.447953", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2023-03-13T03:00:41.448431", "lvl": "input", "msg": "root\n"}
- {"dt": "2023-03-13T03:00:41.549879", "lvl": "target", "msg": "h3ulcb login: root"}
- {"dt": "2023-03-13T03:00:41.551687", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-13T03:00:41.553573", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:18:46)"}
- {"dt": "2023-03-13T03:00:41.568217", "lvl": "target", "msg": "root"}
- {"dt": "2023-03-13T03:00:45.671509", "lvl": "target", "msg": "7[r[999;999H[6n[   46.045145] SDHI0 Vcc: disabling"}
- {"dt": "2023-03-13T03:00:45.695145", "lvl": "target", "msg": "[   46.048368] SDHI3 Vcc: disabling"}
- {"dt": "2023-03-13T03:00:45.697691", "lvl": "target", "msg": "[   46.051604] wlan-en-regulator: disabling"}
- {"dt": "2023-03-13T03:00:45.699603", "lvl": "target", "msg": "[   46.055515] SDHI0 VccQ: disabling"}
- {"dt": "2023-03-13T03:00:45.701638", "lvl": "target", "msg": "[   46.058820] SDHI3 VccQ: disabling"}
- {"dt": "2023-03-13T03:00:45.793603", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-13T03:00:45.795939", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:45) [common]"}
- {"dt": "2023-03-13T03:00:45.797794", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "45.34", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T03:00:45.801737", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:47) [common]"}
- {"dt": "2023-03-13T03:00:45.804144", "lvl": "debug", "msg": "start: 2.4.5 expect-shell-connection (timeout 00:18:41) [common]"}
- {"dt": "2023-03-13T03:00:45.806065", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-13T03:00:45.807530", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-13T03:00:45.808085", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-13T03:00:45.859020", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-13T03:00:45.860383", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2023-03-13T03:00:45.861619", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2023-03-13T03:00:45.872638", "lvl": "target", "msg": ""}
- {"dt": "2023-03-13T03:00:45.888959", "lvl": "debug", "msg": "end: 2.4.5 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T03:00:45.890772", "lvl": "debug", "msg": "start: 2.4.6 overlay-unpack (timeout 00:18:41) [common]"}
- {"dt": "2023-03-13T03:00:45.892448", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-13T03:00:45.994826", "lvl": "target", "msg": "root@h3ulcb:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.016150", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.040399", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2023-03-13T03:00:46.041902", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/32217/compress-overlay-j3u78dxd/overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-13T03:00:46.143837", "lvl": "target", "msg": "root@h3ulcb:~# wget http://192.168.66.1/tmp/32217/compress-overlay-j3u78dxd/overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.151213", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/32217/compress-overlay-j3u78dxd/overllay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.201664", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2023-03-13T03:00:46.204140", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2023-03-13T03:00:46.205000", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  7737  0:00:00 ETA"}
- {"dt": "2023-03-13T03:00:46.205675", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2023-03-13T03:00:46.206923", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-13T03:00:46.309160", "lvl": "target", "msg": "root@h3ulcb:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.331643", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-13T03:00:46.411524", "lvl": "target", "msg": "x lava-32217/"}
- {"dt": "2023-03-13T03:00:46.412799", "lvl": "target", "msg": "x lava-32217/0/"}
- {"dt": "2023-03-13T03:00:46.452140", "lvl": "target", "msg": "x lava-32217/0/lava-test-runner.conf"}
- {"dt": "2023-03-13T03:00:46.453626", "lvl": "target", "msg": "x lava-32217/0/tests/"}
- {"dt": "2023-03-13T03:00:46.455454", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/"}
- {"dt": "2023-03-13T03:00:46.456867", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/README"}
- {"dt": "2023-03-13T03:00:46.458127", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/check_space.sh"}
- {"dt": "2023-03-13T03:00:46.459606", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/check_space.yaml"}
- {"dt": "2023-03-13T03:00:46.461058", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/run.sh"}
- {"dt": "2023-03-13T03:00:46.462434", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/testdef.yaml"}
- {"dt": "2023-03-13T03:00:46.463533", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/testdef_metadata"}
- {"dt": "2023-03-13T03:00:46.464066", "lvl": "target", "msg": "x lava-32217/0/tests/0_check_disk_space/uuid"}
- {"dt": "2023-03-13T03:00:46.464700", "lvl": "target", "msg": "x lava-32217/bin/"}
- {"dt": "2023-03-13T03:00:46.494448", "lvl": "target", "msg": "x lava-32217/bin/lava-add-keys"}
- {"dt": "2023-03-13T03:00:46.495547", "lvl": "target", "msg": "x lava-32217/bin/lava-add-sources"}
- {"dt": "2023-03-13T03:00:46.496444", "lvl": "target", "msg": "x lava-32217/bin/lava-background-process-start"}
- {"dt": "2023-03-13T03:00:46.497804", "lvl": "target", "msg": "x lava-32217/bin/lava-background-process-stop"}
- {"dt": "2023-03-13T03:00:46.498656", "lvl": "target", "msg": "x lava-32217/bin/lava-common-functions"}
- {"dt": "2023-03-13T03:00:46.499675", "lvl": "target", "msg": "x lava-32217/bin/lava-echo-ipv4"}
- {"dt": "2023-03-13T03:00:46.500728", "lvl": "target", "msg": "x lava-32217/bin/lava-install-packages"}
- {"dt": "2023-03-13T03:00:46.501756", "lvl": "target", "msg": "x lava-32217/bin/lava-installed-packages"}
- {"dt": "2023-03-13T03:00:46.502766", "lvl": "target", "msg": "x lava-32217/bin/lava-os-build"}
- {"dt": "2023-03-13T03:00:46.503702", "lvl": "target", "msg": "x lava-32217/bin/lava-probe-channel"}
- {"dt": "2023-03-13T03:00:46.504587", "lvl": "target", "msg": "x lava-32217/bin/lava-probe-ip"}
- {"dt": "2023-03-13T03:00:46.505479", "lvl": "target", "msg": "x lava-32217/bin/lava-target-ip"}
- {"dt": "2023-03-13T03:00:46.506537", "lvl": "target", "msg": "x lava-32217/bin/lava-target-mac"}
- {"dt": "2023-03-13T03:00:46.542723", "lvl": "target", "msg": "x lava-32217/bin/lava-target-storage"}
- {"dt": "2023-03-13T03:00:46.544212", "lvl": "target", "msg": "x lava-32217/bin/lava-test-case"}
- {"dt": "2023-03-13T03:00:46.545961", "lvl": "target", "msg": "x lava-32217/bin/lava-test-event"}
- {"dt": "2023-03-13T03:00:46.547311", "lvl": "target", "msg": "x lava-32217/bin/lava-test-feedback"}
- {"dt": "2023-03-13T03:00:46.548534", "lvl": "target", "msg": "x lava-32217/bin/lava-test-raise"}
- {"dt": "2023-03-13T03:00:46.549779", "lvl": "target", "msg": "x lava-32217/bin/lava-test-reference"}
- {"dt": "2023-03-13T03:00:46.551025", "lvl": "target", "msg": "x lava-32217/bin/lava-test-runner"}
- {"dt": "2023-03-13T03:00:46.552220", "lvl": "target", "msg": "x lava-32217/bin/lava-test-set"}
- {"dt": "2023-03-13T03:00:46.553522", "lvl": "target", "msg": "x lava-32217/bin/lava-test-shell"}
- {"dt": "2023-03-13T03:00:46.554765", "lvl": "target", "msg": "x lava-32217/environment"}
- {"dt": "2023-03-13T03:00:46.555574", "lvl": "target", "msg": "x lava-32217/lava"}
- {"dt": "2023-03-13T03:00:46.555995", "lvl": "target", "msg": "x lava-32217/lava.pub"}
- {"dt": "2023-03-13T03:00:46.556411", "lvl": "target", "msg": "x lava-32217/results/"}
- {"dt": "2023-03-13T03:00:46.556951", "lvl": "target", "msg": "x lava-32217/tests/"}
- {"dt": "2023-03-13T03:00:46.557910", "lvl": "debug", "msg": "end: 2.4.6 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T03:00:46.558643", "lvl": "debug", "msg": "start: 2.4.7 export-device-env (timeout 00:18:41) [common]"}
- {"dt": "2023-03-13T03:00:46.559380", "lvl": "debug", "msg": "end: 2.4.7 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-13T03:00:46.560039", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:01:16) [common]"}
- {"dt": "2023-03-13T03:00:46.560683", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:01:19) [common]"}
- {"dt": "2023-03-13T03:00:46.561362", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:15:00) [common]"}
- {"dt": "2023-03-13T03:00:46.562243", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:15:00) [common]"}
- {"dt": "2023-03-13T03:00:46.562924", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2023-03-13T03:00:46.563574", "lvl": "input", "msg": "#\n"}
- {"dt": "2023-03-13T03:00:46.665089", "lvl": "target", "msg": "root@h3ulcb:~# #"}
- {"dt": "2023-03-13T03:00:46.666750", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:15:00)"}
- {"dt": "2023-03-13T03:00:46.673058", "lvl": "target", "msg": "#"}
- {"dt": "2023-03-13T03:00:46.689598", "lvl": "debug", "msg": "Using /lava-32217"}
- {"dt": "2023-03-13T03:00:46.690611", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2023-03-13T03:00:46.792710", "lvl": "target", "msg": "root@h3ulcb:~# export SHELL=/bin/sh"}
- {"dt": "2023-03-13T03:00:46.800274", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2023-03-13T03:00:46.816859", "lvl": "input", "msg": ". /lava-32217/environment\n"}
- {"dt": "2023-03-13T03:00:46.919339", "lvl": "target", "msg": "root@h3ulcb:~# . /lava-32217/environment"}
- {"dt": "2023-03-13T03:00:46.928570", "lvl": "target", "msg": ". /lava-32217/environment"}
- {"dt": "2023-03-13T03:00:46.944120", "lvl": "input", "msg": "/lava-32217/bin/lava-test-runner /lava-32217/0\n"}
- {"dt": "2023-03-13T03:00:47.047237", "lvl": "target", "msg": "root@h3ulcb:~# /lava-32217/bin/lava-test-runner /lava-32217/0"}
- {"dt": "2023-03-13T03:00:47.051994", "lvl": "info", "msg": "Test shell timeout: 900s (minimum of the action and connection timeout)"}
- {"dt": "2023-03-13T03:00:47.059898", "lvl": "target", "msg": "/lava-32217/bin/lava-test-runner /lava-32217/0"}
- {"dt": "2023-03-13T03:00:47.208802", "lvl": "target", "msg": "+ export TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-13T03:00:47.209893", "lvl": "target", "msg": "+ TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-13T03:00:47.210652", "lvl": "target", "msg": "+ cd /lava-32217/0/tests/0_check_disk_space"}
- {"dt": "2023-03-13T03:00:47.211900", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2023-03-13T03:00:47.212848", "lvl": "target", "msg": "+ UUID=32217_1.5.4.1"}
- {"dt": "2023-03-13T03:00:47.213611", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-13T03:00:47.214338", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_check_disk_space 32217_1.5.4.1>"}
- {"dt": "2023-03-13T03:00:47.215185", "lvl": "target", "msg": "+ ./check_space.sh"}
- {"dt": "2023-03-13T03:00:47.216084", "lvl": "target", "msg": "Space info in /usr:"}
- {"dt": "2023-03-13T03:00:47.217367", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_check_disk_space 32217_1.5.4.1"}
- {"dt": "2023-03-13T03:00:47.218309", "lvl": "info", "msg": "Starting test lava.0_check_disk_space (32217_1.5.4.1)"}
- {"dt": "2023-03-13T03:00:47.219344", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2023-03-13T03:00:47.257871", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-13T03:00:47.259391", "lvl": "target", "msg": "/dev/nbd0       3.5G  2.5G  863M  75% /"}
- {"dt": "2023-03-13T03:00:47.260613", "lvl": "target", "msg": "Space info in /var/run"}
- {"dt": "2023-03-13T03:00:47.262668", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-13T03:00:47.264070", "lvl": "target", "msg": "tmpfs           1.6G  9.4M  1.6G   1% /run"}
- {"dt": "2023-03-13T03:00:47.265321", "lvl": "target", "msg": "Space info in /"}
- {"dt": "2023-03-13T03:00:47.266432", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-13T03:00:47.267651", "lvl": "target", "msg": "/dev/nbd0       3.5G  2.5G  863M  75% /"}
- {"dt": "2023-03-13T03:00:47.268766", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-13T03:00:47.269898", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_check_disk_space 32217_1.5.4.1>"}
- {"dt": "2023-03-13T03:00:47.271072", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2023-03-13T03:00:47.272255", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_check_disk_space 32217_1.5.4.1"}
- {"dt": "2023-03-13T03:00:47.273667", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2023-03-13T03:00:47.274797", "lvl": "info", "msg": "Ending test lava.0_check_disk_space (32217_1.5.4.1), duration 0.06"}
- {"dt": "2023-03-13T03:00:47.276193", "lvl": "results", "msg": {"case": "0_check_disk_space", "commit_id": "1e1ec669234e47bfb23f24869a10c9df614654f4", "definition": "lava", "duration": "0.06", "namespace": "common", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "result": "pass", "revision": "unspecified", "uuid": "32217_1.5.4.1"}}
- {"dt": "2023-03-13T03:00:47.278416", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2023-03-13T03:00:47.279837", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T03:00:47.280962", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T03:00:47.282206", "lvl": "info", "msg": "start: 4 finalize (timeout 00:30:00) [common]"}
- {"dt": "2023-03-13T03:00:47.283608", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-13T03:00:47.284816", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2023-03-13T03:00:50.720702", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-13T03:00:50.754640", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-03-13T03:00:50.858669", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-03-13T03:00:50.861049", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.58", "extra": {"status": "success"}, "level": "4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-13T03:00:50.865474", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-03-13T03:00:50.870241", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-03-13T03:00:51.873555", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-13T03:00:51.875963", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-03-13T03:00:51.877745", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-13T03:00:51.981247", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-03-13T03:00:51.983481", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-03-13T03:00:51.985294", "lvl": "debug", "msg": "clean NBD port 61999"}
- {"dt": "2023-03-13T03:00:55.248278", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:08) [common]"}
- {"dt": "2023-03-13T03:00:55.254113", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-13T03:00:55.255800", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/initrd"}
- {"dt": "2023-03-13T03:00:55.257783", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/kernel"}
- {"dt": "2023-03-13T03:00:55.266362", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/dtb"}
- {"dt": "2023-03-13T03:00:55.267869", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32217/nbd-deploy-319e6xp6/nbdroot"}
- {"dt": "2023-03-13T03:00:55.976097", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32217"}
- {"dt": "2023-03-13T03:00:55.984866", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32217"}
- {"dt": "2023-03-13T03:00:55.985890", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2023-03-13T03:00:55.986577", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
