amitsagargowda commented 2 years ago

We are trying swupdate on our iot2050 using the example mentioned in iot meta-2050 readme.md "$ swupdate -i iot2050-image-swu-example-iot2050-debian-iot2050.swu" .

But this fails with an error "Error: Not Enough free Space to extract img1.gz " .

We are trying on PG1 FS03 using 32GB SD card. Base os is updated to commit id "78a1eaf924579aca87e26d6f511ecc0b660f0515".

We have updated event Bootloader to latest one compitable to PG2 OS.

MicrosoftTeams-image

jan-kiszka commented 2 years ago

@gylstorffq Where does swupdate extract the swu here, on /tmp or on the medium where it is picked up from? @amitsagargowda What does df -h report in your case? Does it work when you first move the swu to /data or even /home/root and use it from there?

gylstorffq commented 2 years ago

The swu binary is extracted to /tmp.

amitsagargowda commented 2 years ago

@gylstorffq: is there a way to extract to different folder as there is not sufficient space is /tmp and currently it has only 312MB free.

I just followed the steps mentioned in README for swupdate

jan-kiszka commented 2 years ago

That increase may relate to the addition of Google Coral support. You can disable that if you don't need it. Or increase the root partition sizes.

Otherwise, you can set TMPDIR before calling swupdate to make it use a different temp folder.

amitsagargowda commented 2 years ago

@jan-kiszka: i tried setting TMPDIR and getting below error.

The /mnt/tmp is folder created in usb which has free space of 27GB.

jan-kiszka commented 2 years ago

What's the filesystem of /dev/sda1? Some FAT?

gylstorffq commented 2 years ago

I have build 78a1eaf924579aca87e26d6f511ecc0b660f0515. The image has around 1.5G free disk space on root. The commit doesn't contain meta-coral.

@amitsagargowda If you have installed additional software i would propose to adjust the disk sizes at https://github.com/siemens/meta-iot2050/blob/78a1eaf924579aca87e26d6f511ecc0b660f0515/wic/iot2050-swu.wks.in#L14 and https://github.com/siemens/meta-iot2050/blob/78a1eaf924579aca87e26d6f511ecc0b660f0515/wic/iot2050-swu.wks.in#L17.

amitsagargowda commented 2 years ago

@jan-kiszka : Yes the filesytem is of type FAT

jan-kiszka commented 2 years ago

Yes, FAT won't work as tmp because swupdate will try to use it to create files that require a real Unix filesystem (e.g. the one used for /data).

BTW, the example image with Coral support still has plenty of free space:

/dev/root       3.8G  1.7G  1.9G  48% /

So, if you already customize your rootfs, you can also adjust its size like Quirin suggested.

amitsagargowda commented 2 years ago

@jan-kiszka : Thanks for the update i did try formatting my usb to ext4 and not swupdate works logs can be seen in figure below. But after reboot it is not moving form one rootfs to other rootfs I am getting following error what is going wrong here.

Finally we see that it stills in same rootfs and not moved to flashed rootfs. Any support here will help us a lot.

We know that at the end we need to run script " complete_update.sh success" after we observe that rootfs has shifted correctly but since rootfs is not shifted we did not run script complete_update.sh.

root@iot2050-debian:/media/usb0# TMPDIR=/media/usb0/tmp/ swupdate -v -i ./iot2050-image-swu-example-iot2050-debian-iot2050.swu Swupdate v2021.04.0

Licensed under GPLv2. See source distribution for detailed copyright notices.

[INFO ] : SWUPDATE running : [lua_handlers_init] : Lua handler(s) found. [INFO ] : SWUPDATE running : [print_registered_handlers] : Registered handlers: [INFO ] : SWUPDATE running : [print_registered_handlers] : dummy [INFO ] : SWUPDATE running : [print_registered_handlers] : archive [INFO ] : SWUPDATE running : [print_registered_handlers] : tar [INFO ] : SWUPDATE running : [print_registered_handlers] : uboot [INFO ] : SWUPDATE running : [print_registered_handlers] : bootloader [INFO ] : SWUPDATE running : [print_registered_handlers] : diskpart [INFO ] : SWUPDATE running : [print_registered_handlers] : uniqueuuid [INFO ] : SWUPDATE running : [print_registered_handlers] : lua [INFO ] : SWUPDATE running : [print_registered_handlers] : raw [INFO ] : SWUPDATE running : [print_registered_handlers] : rawfile [INFO ] : SWUPDATE running : [print_registered_handlers] : rawcopy [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_image [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_file [INFO ] : SWUPDATE running : [print_registered_handlers] : shellscript [INFO ] : SWUPDATE running : [print_registered_handlers] : preinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : postinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : roundrobin [TRACE] : SWUPDATE running : [listener_create] : got no socket at /media/usb0/tmp//swupdateprog from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /media/usb0/tmp//swupdateprog [TRACE] : SWUPDATE running : [network_initializer] : Main loop daemon [TRACE] : SWUPDATE running : [listener_create] : got no socket at /media/usb0/tmp//sockinstctrl from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /media/usb0/tmp//sockinstctrl [TRACE] : SWUPDATE running : [network_thread] : Incoming network request: processing... [INFO ] : SWUPDATE started : Software Update started ! [TRACE] : SWUPDATE running : [network_initializer] : Software update started [TRACE] : SWUPDATE running : [extract_file_to_tmp] : Found file [TRACE] : SWUPDATE running : [extract_file_to_tmp] : filename sw-description [TRACE] : SWUPDATE running : [extract_file_to_tmp] : size 577 [TRACE] : SWUPDATE running : [get_common_fields] : Version 0.1 [TRACE] : SWUPDATE running : [add_properties] : Found properties for img1.gz: [TRACE] : SWUPDATE running : [add_properties_cb] : Property subtype: image [TRACE] : SWUPDATE running : [_parse_images] : Found compressed Image: img1.gz in device : rootfs1,rootfs2 for handler roundrobin [TRACE] : SWUPDATE running : [extract_files] : Found file [TRACE] : SWUPDATE running : [extract_files] : filename img1.gz [TRACE] : SWUPDATE running : [extract_files] : size 1863127600 required [TRACE] : SWUPDATE running : [extract_padding] : Expecting 340 padding bytes at end-of-file [TRACE] : SWUPDATE running : [network_initializer] : Valid image found: copying to FLASH [INFO ] : SWUPDATE running : Installation in progress [TRACE] : SWUPDATE running : [install_single_image] : Found installer for stream img1.gz roundrobin [TRACE] : SWUPDATE running : [notify_helper] : Round Robin Handler: No compiled-in config found, trying /etc/swupdate.handler.ini [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Using '/dev/disk/by-partlabel/rootfs2' as target via 'raw' handler. [TRACE] : SWUPDATE running : [lua_dump_table] : properties subtype = image [TRACE] : SWUPDATE running : [lua_dump_table] : Inserting property subtype = image [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Setting bootloader environment: ustate=1 [TRACE] : SWUPDATE running : [l_handler_wrapper] : [Lua handler] returned: 0 [INFO ] : SWUPDATE successful ! SWUPDATE successful ! [TRACE] : SWUPDATE running : [network_initializer] : Main thread sleep again ! [INFO ] : No SWUPDATE running : Waiting for requests... [INFO ] : SWUPDATE running : [endupdate] : Swupdate was successful !

jan-kiszka commented 2 years ago

The watchdog error indicates that you are not using latest master for your tests. Correct? We fixed some issues there, and things worked after that, at least for me.

amitsagargowda commented 2 years ago

@jan-kiszka : Which commit id should we refer. Currently we are using commit id 78a1eaf924579aca87e26d6f511ecc0b660f0515

jan-kiszka commented 2 years ago

master, bf54a81e1aa6bab868904cc4b66847e2e38c0ea4.

amitsagargowda commented 2 years ago

@jan-kiszka : Currently we are using bootloader version mentioned below is this fine.

jan-kiszka commented 2 years ago

It should be fine, yes.

There are many variants and versions by now, so we may always miss some corner case, specifically with rather fresh features. Please again with a vanilla image built from today's master. If that one shows the same error as before, I need to reproduce (don't have the exact same setup at hands right now).

amitsagargowda commented 2 years ago

@jan-kiszka: I am trying to build image from commit id mentioned but i get following error. The build option used was " ./kas-container build kas-iot2050-example.yml" . Are we doing anything wrong here.

jan-kiszka commented 2 years ago

Please retry after running ./kas-container --isar clean first.

amitsagargowda commented 2 years ago

@jan-kiszka : I tried "./kas-container --isar clean" as suggested above and tried build options "./kas-container build kas-iot2050-example.yml" . But still i do get same error.

jan-kiszka commented 2 years ago

Then please share the log file, rather than screenshots.

amitsagargowda commented 2 years ago

@jan-kiszka : what log file would you expect . can you please let me know so i can upload it.

amitsagargowda commented 2 years ago

Please find the logs files attached i hope this is what you are expecting. log.do_rootfs_install.279.txt

jan-kiszka commented 2 years ago

Double-check that you are not having any local modifications on top. E.g., I don't see why openssl-dev should be installed on the original version of bf54a81e1aa6bab868904cc4b66847e2e38c0ea4 (it's not installed when I build it here).

You might also be bitten by the new sstate cache and a Debian update of openssl in the meantime. That can be cleaned via ./kas-container --isar cleansstate. If all that still does not help, I would need the console log (./kas-container build kas-iot2050-example.yml > build.log).

Note that you will want ./kas-container build kas-iot2050-swupdate.yml for the SWUpdate-enabled image in the end.

amitsagargowda commented 2 years ago

@jan-kiszka : I tried the cleansstate command but it seem to fail. Are we entering correct command.

jan-kiszka commented 2 years ago

Nope, but the kas-container version in master is not recent enough. Just to rm -r build/sstate-cache/ instead.

jan-kiszka commented 2 years ago

We might indeed have a problem in the openssl recipe, just received another report. Hang on!

BaochengSu commented 2 years ago

Our CI is broken now also due to the openssl issue.

BaochengSu commented 2 years ago

@AsuraZeng already trigger a new CI to fix it, although that will not be the final solution.

jan-kiszka commented 2 years ago

This will resolve the issue (for clean rebuilds):

diff --git a/recipes-security/openssl/openssl_latest.bb b/recipes-security/openssl/openssl_latest.bb
index d498c46e..f55e69b1 100644
--- a/recipes-security/openssl/openssl_latest.bb
+++ b/recipes-security/openssl/openssl_latest.bb
@@ -11,7 +11,7 @@
 inherit dpkg

 SRC_URI = " \
-    apt://${PN}/${BASE_DISTRO_CODENAME} \
+    apt://${PN} \
     file://0001-make-bnrand_range-reliable-with-deterministic-run-ti.patch;apply=no \
     "
 CHANGELOG_V="<orig-version>+iot2050"

jan-kiszka commented 2 years ago

Specifically, a clean of the download cache for openssl seems to be needed (./kas-container build kas-iot2050-example.yml --target openssl -c cleanall, then rebuild).

amitsagargowda commented 2 years ago

@jan-kiszka : Thanks a lot it works now 👍 ... Now i would verify my swupdate ... Just a question ... i see the last patch you pointed to for my issue was related to u-boot. Should i update uboot on my pg1 board?

amitsagargowda commented 2 years ago

@jan-kiszka : Can you please confirm if i need to update the base image or u-boot for this swupdate feature to work?

jan-kiszka commented 2 years ago

You need firmware 1.2.1, and I would strongly recommend to build from master.

Note that the SWUpdate feature should be fine in principle already, but some details will still need work. And the ongoing effort to enable secure boot for it may even bring more fundamental architectural changes (we are exploring if switching to UEFI wouldn't be better).

amitsagargowda commented 2 years ago

@jan-kiszka : I build the firmware from master and updated it still i find the issue that rootfs is shifted still boots with old kernel i have attached detailed logs can you please have a look and mention what is going wrong here.

ebian-iot2050.swuan:/media/usb0# TMPDIR=/media/usb0/tmp/ swupdate -v -i ./iot2050-image-swu-example-iot2050-debian-iot2050.swu Swupdate v2021.04.0

Licensed under GPLv2. See source distribution for detailed copyright notices.

[INFO ] : SWUPDATE running : [lua_handlers_init] : Lua handler(s) found. [INFO ] : SWUPDATE running : [print_registered_handlers] : Registered handlers: [INFO ] : SWUPDATE running : [print_registered_handlers] : dummy [INFO ] : SWUPDATE running : [print_registered_handlers] : archive [INFO ] : SWUPDATE running : [print_registered_handlers] : tar [INFO ] : SWUPDATE running : [print_registered_handlers] : uboot [INFO ] : SWUPDATE running : [print_registered_handlers] : bootloader [INFO ] : SWUPDATE running : [print_registered_handlers] : diskpart [INFO ] : SWUPDATE running : [print_registered_handlers] : uniqueuuid [INFO ] : SWUPDATE running : [print_registered_handlers] : lua [INFO ] : SWUPDATE running : [print_registered_handlers] : raw [INFO ] : SWUPDATE running : [print_registered_handlers] : rawfile [INFO ] : SWUPDATE running : [print_registered_handlers] : rawcopy [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_image [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_file [INFO ] : SWUPDATE running : [print_registered_handlers] : shellscript [INFO ] : SWUPDATE running : [print_registered_handlers] : preinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : postinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : roundrobin [TRACE] : SWUPDATE running : [listener_create] : got no socket at /media/usb0/tmp//swupdateprog from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /media/usb0/tmp//swupdateprog [TRACE] : SWUPDATE running : [network_initializer] : Main loop daemon [TRACE] : SWUPDATE running : [listener_create] : got no socket at /media/usb0/tmp//sockinstctrl from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /media/usb0/tmp//sockinstctrl [TRACE] : SWUPDATE running : [network_thread] : Incoming network request: processing... [INFO ] : SWUPDATE started : Software Update started ! [TRACE] : SWUPDATE running : [network_initializer] : Software update started [TRACE] : SWUPDATE running : [extract_file_to_tmp] : Found file [TRACE] : SWUPDATE running : [extract_file_to_tmp] : filename sw-description [TRACE] : SWUPDATE running : [extract_file_to_tmp] : size 577 [TRACE] : SWUPDATE running : [get_common_fields] : Version 0.1 [TRACE] : SWUPDATE running : [add_properties] : Found properties for img1.gz: [TRACE] : SWUPDATE running : [add_properties_cb] : Property subtype: image [TRACE] : SWUPDATE running : [_parse_images] : Found compressed Image: img1.gz in device : rootfs1,rootfs2 for handler roundrobin [TRACE] : SWUPDATE running : [extract_files] : Found file [TRACE] : SWUPDATE running : [extract_files] : filename img1.gz [TRACE] : SWUPDATE running : [extract_files] : size 1863127600 required [TRACE] : SWUPDATE running : [extract_padding] : Expecting 340 padding bytes at end-of-file [TRACE] : SWUPDATE running : [network_initializer] : Valid image found: copying to FLASH [INFO ] : SWUPDATE running : Installation in progress [TRACE] : SWUPDATE running : [install_single_image] : Found installer for stream img1.gz roundrobin [TRACE] : SWUPDATE running : [notify_helper] : Round Robin Handler: No compiled-in config found, trying /etc/swupdate.handler.ini [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Using '/dev/disk/by-partlabel/rootfs2' as target via 'raw' handler. [TRACE] : SWUPDATE running : [lua_dump_table] : properties subtype = image [TRACE] : SWUPDATE running : [lua_dump_table] : Inserting property subtype = image [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Setting bootloader environment: ustate=1 [TRACE] : SWUPDATE running : [l_handler_wrapper] : [Lua handler] returned: 0 [INFO ] : SWUPDATE successful ! SWUPDATE successful ! [TRACE] : SWUPDATE running : [network_initializer] : Main thread sleep again ! [INFO ] : No SWUPDATE running : Waiting for requests... [INFO ] : SWUPDATE running : [endupdate] : Swupdate was successful !

root@iot2050-debian:/media/usb0# reboot Stopping Sessio[ OK ] Removed slice system-modprobe.slice. [ OK ] Removed slice system-swupdate\x2dusb.slice. [ OK ] Stopped target Bluetooth. [ OK ] Stopped target Graphical Interface. [ OK ] Stopped target Timers. [ OK ] Stopped Daily apt upgrade and clean activities. [ OK ] Stopped Daily apt download activities. [ OK ] Stopped Periodic ext4 Onli…ata Check for All Filesystems. [ OK ] Stopped Discard unused blocks once a week. [ OK ] Stopped Daily Cleanup of Temporary Directories. [ OK ] Stopped target System Time Synchronized. [ OK ] Stopped target System Time Set. [ OK ] Closed Load/Save RF Kill Switch Status /dev/rfkill Watch. Stopping Bluetooth service... Stopping Configure status LED... Stopping usbmount@dev-sda.service... Stopping usbmount@dev-sda1.service... Stopping watchdog daemon... [ OK ] Stopped Serial Getty on ttyS3. [ OK ] Unmounted /media/usb0. [ OK ] Stopped Configure status LED. [ OK ] Stopped usbmount@dev-sda.service. [ OK ] Stopped Bluetooth service. [ OK ] Stopped Session c2 of user root. [ OK ] Stopped watchdog daemon. [ OK ] Stopped Session c1 of user root. [ OK ] Removed slice system-serial\x2dgetty.slice. [ OK ] Stopped target Multi-User System. [ OK ] Stopped target Login Prompts. Stopping Modem Manager... Stopping cc220 Service.... Stopping Getty on tty1... Stopping System Logging Service... Stopping OpenBSD Secure Shell server... Stopping swupdate progress service... Stopping User Login Management... Stopping tcf-agent... Stopping User Manager for UID 0... [ OK ] Stopped System Logging Service. [ OK ] Stopped swupdate progress service. [ OK ] Stopped cc220 Service.. [ OK ] Stopped Modem Manager. [ OK ] Stopped tcf-agent. [ OK ] Stopped Getty on tty1. [ OK ] Stopped OpenBSD Secure Shell server. [ OK ] Stopped User Manager for UID 0. [ OK ] Removed slice system-getty.slice. Stopping Authorization Manager... [ OK ] Stopped Regenerate sshd host keys. Stopping SWUpdate daemon... Stopping Load/Save Random Seed... Stopping User Runtime Directory /run/user/0... [ OK ] Stopped Authorization Manager. [ OK ] Stopped SWUpdate daemon. [ OK ] Unmounted /run/user/0. [ OK ] Stopped User Login Management. [ OK ] Stopped usbmount@dev-sda1.service. [ OK ] Stopped User Runtime Directory /run/user/0. [ OK ] Removed slice system-usbmount.slice. [ OK ] Removed slice User Slice of UID 0. [ OK ] Reached target Unmount All Filesystems. Stopping Permit User Sessions... [ OK ] Stopped Load/Save Random Seed. [ OK ] Stopped Permit User Sessions. [ OK ] Stopped target Network. [ OK ] Stopped target Remote File Systems. Stopping Network Manager... Stopping Raise network interfaces... Stopping WPA supplicant... [ OK ] Stopped WPA supplicant. [ OK ] Stopped Raise network interfaces. [ OK ] Stopped Network Manager. [ OK ] Stopped target Network (Pre). Stopping D-Bus System Message Bus... [ OK ] Stopped D-Bus System Message Bus. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Removed slice User and Session Slice. [ OK ] Stopped target Sockets. [ OK ] Closed D-Bus System Message Bus Socket. [ OK ] Closed SWUpdate socket listener. [ OK ] Closed Syslog Socket. [ OK ] Closed UUID daemon activation socket. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password …ts to Console Directory Watch. [ OK ] Stopped Forward Password R…uests to Wall Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Load Kernel Modules. Stopping Network Time Synchronization... Stopping Update UTMP about System Boot/Shutdown... [ OK ] Stopped Network Time Synchronization. [ OK ] Stopped Update UTMP about System Boot/Shutdown. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local File Systems (Pre). [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create System Users. [ OK ] Stopped Remount Root and Kernel File Systems. [ OK ] Reached target Shutdown. [ OK ] Reached target Final Step. [ OK ] Finished Reboot. [ OK ] Reached target Reboot. [ 3361.621777] watchdog: watchdog0: nowayout prevents watchdog being stopped! [ 3361.628769] watchdog: watchdog0: watchdog did not stop! [ 3362.534056] systemd-shutdown[1]: Syncing filesystems and block devices. [ 3366.134921] systemd-shutdown[1]: Sending SIGTERM to remaining processes... [ 3366.157945] systemd-journald[181]: Received SIGTERM from PID 1 (systemd-shutdow). [ 3366.555372] systemd-shutdown[1]: Sending SIGKILL to remaining processes... [ 3366.574755] systemd-shutdown[1]: Using hardware watchdog 'K3 RTI Watchdog', version 0, device /dev/watchdog [ 3366.587219] systemd-shutdown[1]: Unmounting file systems. [ 3366.595691] [791]: Remounting '/' read-only in with options '(null)'. [ 3366.738030] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null) [ 3366.753057] systemd-shutdown[1]: All filesystems unmounted. [ 3366.758690] systemd-shutdown[1]: Deactivating swaps. [ 3366.763904] systemd-shutdown[1]: All swaps deactivated. [ 3366.769149] systemd-shutdown[1]: Detaching loop devices. [ 3366.779369] systemd-shutdown[1]: All loop devices detached. [ 3366.785007] systemd-shutdown[1]: Stopping MD devices. [ 3366.790478] systemd-shutdown[1]: All MD devices stopped. [ 3366.795805] systemd-shutdown[1]: Detaching DM devices. [ 3366.801276] systemd-shutdown[1]: All DM devices detached. [ 3366.806692] systemd-shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. [ 3366.831497] systemd-shutdown[1]: Syncing filesystems and block devices.

[ 3366.860335] reboot: Restarting system AVS@[1100 1200 1200] SIMATIC IOT2050 SE-Boot Version: D01.02.02.02-0-g3af64a90-0x0000 BuildDate: 20220301 SYSFW ABI: 3.1 [version: 21] [21.5.0--v2021.05 (Terrific Llam]

Board: IOT2050-ADVANCED Serial: M5BM8308 MLFB: 6ES7647-0BA00-1YA2 UUID: 7FFD2E1C9B1C4BAC98DEE2B111EB527C A5E: A5E452229880AA04 MAC[0]: e0-dc-a0-c4-91-df MAC[1]: e0-dc-a0-c4-91-de SKU: SE Loading PK... ok PK count: 00 PK version: 00 SV: 00-00 Security ID 0x5125e53f-0x842e215d Security policy: soft

Warning: 0x50000000 Validating FIT... Loading image atf... Loading image tee... Loading image spl... Loading image k3-am65-iot2050-spl.dtb... NOTICE: BL31: v2.6(release): NOTICE: BL31: Built : 10:38:15, Mar 16 2022 I/TC: I/TC: OP-TEE version: 3.16.0 (gcc version 10.2.1 20210110 (Debian 10.2.1-6)) #1 Wed Mar 16 10:38:15 UTC 2022 aarch64 I/TC: WARNING: This OP-TEE configuration might be insecure! I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html I/TC: Primary CPU initializing I/TC: Primary CPU switching to normal world boot

U-Boot SPL 2022.01-V01.02.01-78-gbf54a81 (Mar 17 2022 - 05:05:01 +0000) SYSFW ABI: 3.1 (firmware rev 0x0015 '21.5.0--v2021.05 (Terrific Llam') Trying to boot from SPI

U-Boot 2022.01-V01.02.01-78-gbf54a81 (Mar 17 2022 - 05:05:01 +0000)

Model: SIMATIC IOT2050 Advanced DRAM: 2 GiB WDT: Not starting watchdog@40610000 MMC: mmc@4f80000: 1, mmc@4fa0000: 0 Loading Environment from SPIFlash... SF: Detected w25q128 with page size 256 Bytes, erase size 64 KiB, total 16 MiB OK In: serial Out: serial Err: serial Net: No ethernet found. Hit any key to stop autoboot: 0 switch to partitions #0, OK mmc1(part 0) is current device No partition table - mmc 1 Couldn't find partition mmc 1:1 switch to partitions #0, OK mmc0 is current device Scanning mmc 0:1... Found U-Boot script /boot/boot.scr 729 bytes read in 28 ms (25.4 KiB/s)

Executing script at 80000000

Loading /usr/lib/linux-image-5.10.64/ti/k3-am6548-iot2050-advanced.dtb... 48596 bytes read in 36 ms (1.3 MiB/s) Loading /boot/vmlinux-5.10.64... 18296840 bytes read in 767 ms (22.7 MiB/s)

Flattened Device Tree blob at 88000000

Booting using the fdt blob at 0x88000000 Loading Device Tree to 000000008fff1000, end 000000008ffffdd3 ... OK

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.10.64 (builder@6afbeae32a22) (aarch64-linux-gnu-gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2) #1 SMP PREEMPT Tue Mar 8 11:19:10 UTC 2022 [ 0.000000] Machine model: SIMATIC IOT2050 Advanced [ 0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002810000 (options '') [ 0.000000] printk: bootconsole [ns16550a0] enabled [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000080000000-0x00000000ffffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000080000000-0x000000009e7fffff] [ 0.000000] node 0: [mem 0x000000009e800000-0x00000000a21fffff] [ 0.000000] node 0: [mem 0x00000000a2200000-0x00000000ffffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000000ffffffff] [ 0.000000] cma: Reserved 24 MiB at 0x00000000fb800000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 22 pages/cpu s49624 r8192 d32296 u90112 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] CPU features: detected: GIC system register CPU interface [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Kernel command line: root=PARTLABEL=rootfs1 console=ttyS3,115200n8 earlycon=ns16550a,mmio32,0x02810000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),64k(pru0-fw),64k(pru1-fw),64k(rtu0-fw),64k(rtu1-fw),-@8m(ospi.rootfs) rootwait [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 1954364K/2097152K available (11134K kernel code, 1036K rwdata, 3996K rodata, 1664K init, 430K bss, 118212K reserved, 24576K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode [ 0.000000] GICv3: 960 SPIs implemented [ 0.000000] GICv3: 0 Extended SPIs implemented [ 0.000000] GICv3: Distributor has no Range Selector support [ 0.000000] GICv3: 16 PPIs implemented [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000 [ 0.000000] ITS [mem 0x01820000-0x0182ffff] [ 0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS [ 0.000000] ITS@0x0000000001820000: Devices Table too large, reduce ids 20->19 [ 0.000000] ITS@0x0000000001820000: allocated 524288 Devices @80800000 (flat, esz 8, psz 64K, shr 0) [ 0.000000] ITS: using cache flushing for cmd queue [ 0.000000] GICv3: using LPI property table @0x0000000080040000 [ 0.000000] GIC: using cache flushing for LPI property table [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000080050000 [ 0.000000] random: get_random_bytes called from start_kernel+0x318/0x4cc with crng_init=0 [ 0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns [ 0.000005] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns [ 0.008619] Console: colour dummy device 80x25 [ 0.013220] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000) [ 0.023914] pid_max: default: 32768 minimum: 301 [ 0.028760] LSM: Security Framework initializing [ 0.033563] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.041150] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.051332] rcu: Hierarchical SRCU implementation. [ 0.056604] Platform MSI: msi-controller@1820000 domain created [ 0.062903] PCI/MSI: /bus@100000/interrupt-controller@1800000/msi-controller@1820000 domain created [ 0.072505] smp: Bringing up secondary CPUs ... I/TC: Secondary CPU 1 initializing I/TC: Secondary CPU 1 switching to normal world boot I/TC: Secondary CPU 2 initializing I/TC: Secondary CPU 2 switching to normal world boot I/TC: Secondary CPU 3 initializing I/TC: Secondary CPU 3 switching to normal world boot [ 0.086156] Detected VIPT I-cache on CPU1 [ 0.086203] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000 [ 0.086218] GICv3: CPU1: using allocated LPI pending table @0x0000000080060000 [ 0.086282] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.095467] Detected VIPT I-cache on CPU2 [ 0.095512] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000 [ 0.095528] GICv3: CPU2: using allocated LPI pending table @0x0000000080070000 [ 0.095598] CPU2: Booted secondary processor 0x0000000100 [0x410fd034] [ 0.104741] Detected VIPT I-cache on CPU3 [ 0.104769] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000 [ 0.104780] GICv3: CPU3: using allocated LPI pending table @0x0000000080080000 [ 0.104817] CPU3: Booted secondary processor 0x0000000101 [0x410fd034] [ 0.104972] smp: Brought up 1 node, 4 CPUs [ 0.185122] SMP: Total of 4 processors activated. [ 0.189943] CPU features: detected: 32-bit EL0 Support [ 0.195224] CPU features: detected: CRC32 instructions [ 0.217149] CPU: All CPU(s) started at EL2 [ 0.221390] alternatives: patching kernel code [ 0.227380] devtmpfs: initialized [ 0.240237] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.250239] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.258649] pinctrl core: initialized pinctrl subsystem [ 0.265212] NET: Registered protocol family 16 [ 0.271979] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations [ 0.279425] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.287628] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.296417] thermal_sys: Registered thermal governor 'step_wise' [ 0.296976] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.310218] ASID allocator initialised with 65536 entries [ 0.353141] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.360026] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages [ 0.366910] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.373773] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages [ 0.385807] k3-chipinfo 43000014.chipid: Family:AM65X rev:SR1.0 JTAGID[0x0bb5a02f] Detected [ 0.395574] iommu: Default domain type: Translated [ 0.400954] SCSI subsystem initialized [ 0.405038] usbcore: registered new interface driver usbfs [ 0.410705] usbcore: registered new interface driver hub [ 0.416178] usbcore: registered new device driver usb [ 0.421823] mc: Linux media interface: v0.10 [ 0.426237] videodev: Linux video capture interface: v2.00 [ 0.431919] EDAC MC: Ver: 3.0.0 [ 0.436632] Advanced Linux Sound Architecture Driver Initialized. [ 0.443767] clocksource: Switched to clocksource arch_sys_counter [ 0.450263] VFS: Disk quotas dquot_6.6.0 [ 0.454349] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.468952] NET: Registered protocol family 2 [ 0.473677] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 0.482751] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) [ 0.491587] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.499870] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 0.507700] TCP: Hash tables configured (established 16384 bind 16384) [ 0.514592] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.521527] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.529047] NET: Registered protocol family 1 [ 0.534357] RPC: Registered named UNIX socket transport module. [ 0.540456] RPC: Registered udp transport module. [ 0.545281] RPC: Registered tcp transport module. [ 0.550105] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.556712] PCI: CLS 0 bytes, default 64 [ 0.561690] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available [ 0.571406] Initialise system trusted keyrings [ 0.576188] workingset: timestamp_bits=46 max_order=19 bucket_order=0 [ 0.588324] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.595024] NFS: Registering the id_resolver key type [ 0.600239] Key type id_resolver registered [ 0.604526] Key type id_legacy registered [ 0.608723] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.615600] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 0.623425] 9p: Installing v9fs 9p2000 file system support [ 0.683293] Key type asymmetric registered [ 0.687501] Asymmetric key parser 'x509' registered [ 0.692533] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.700248] io scheduler mq-deadline registered [ 0.704907] io scheduler kyber registered [ 0.711504] pinctrl-single 4301c000.pinctrl: 70 pins, size 280 [ 0.718132] pinctrl-single 11c000.pinctrl: 185 pins, size 740 [ 0.724211] pinctrl-single 11c2e8.pinctrl: 9 pins, size 36 [ 0.734816] k3-ringacc 2b800000.ringacc: Failed to get MSI domain [ 0.741167] k3-ringacc 3c000000.ringacc: Failed to get MSI domain [ 0.751619] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled [ 0.776136] brd: module loaded [ 0.788349] loop: module loaded [ 0.794729] libphy: Fixed MDIO Bus: probed [ 0.799781] tun: Universal TUN/TAP device driver, 1.6 [ 0.805730] igbvf: Intel(R) Gigabit Virtual Function Network Driver [ 0.812163] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 0.818293] sky2: driver version 1.30 [ 0.822643] VFIO - User Level meta-driver version: 0.3 [ 0.829629] usbcore: registered new interface driver usb-storage [ 0.836201] i2c /dev entries driver [ 0.840900] sdhci: Secure Digital Host Controller Interface driver [ 0.847231] sdhci: Copyright(c) Pierre Ossman [ 0.852284] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.859692] ledtrig-cpu: registered to indicate activity on CPUs [ 0.865952] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource [ 0.873566] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... [ 0.881277] optee: probing for conduit method. [ 0.885872] optee: revision 3.16 [ 0.886417] optee: initialized driver [ 0.895232] NET: Registered protocol family 17 [ 0.899969] 9pnet: Installing 9P2000 support [ 0.904413] Key type dns_resolver registered [ 0.908981] registered taskstats version 1 [ 0.913198] Loading compiled-in X.509 certificates [ 0.928387] k3-ringacc 2b800000.ringacc: Failed to get MSI domain [ 0.934885] k3-ringacc 3c000000.ringacc: Failed to get MSI domain [ 0.946945] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource [ 0.954591] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: ABI: 3.1 (firmware rev 0x0015 '21.5.0--v2021.05 (Terrific Llam') [ 1.025318] random: fast init done [ 1.046449] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 1.052446] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 1.059532] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 1.065438] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 1.072322] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 1.078223] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 1.086747] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz [ 1.108850] pca953x 1-0020: supply vcc not found, using dummy regulator [ 1.115794] pca953x 1-0020: using no AI [ 1.120780] pca953x 1-0021: supply vcc not found, using dummy regulator [ 1.127666] pca953x 1-0021: using no AI [ 1.132619] pca953x 1-0025: supply vcc not found, using dummy regulator [ 1.139505] pca953x 1-0025: using no AI [ 1.144159] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 400 kHz [ 1.173530] rtc-pcf8563 2-0051: registered as rtc0 [ 1.178819] rtc-pcf8563 2-0051: setting system clock to 2022-03-23T04:49:49 UTC (1648010989) [ 1.188383] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz [ 1.195080] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz [ 1.201699] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz [ 1.208974] omap_i2c 2030000.i2c: bus 5 rev0.12 at 400 kHz [ 1.215902] ti-sci-intr 42200000.interrupt-controller: Interrupt Router 156 domain created [ 1.224644] ti-sci-intr bus@100000:interrupt-controller@a00000: Interrupt Router 100 domain created [ 1.234141] ti-sci-intr 310e0000.interrupt-controller: Interrupt Router 182 domain created [ 1.242969] ti-sci-inta 33d00000.interrupt-controller: Interrupt Aggregator domain 179 created [ 1.260601] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,32] sci-dev-id:195 [ 1.270509] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: enabled [ 1.277197] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.288198] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:818, gp-rings[304,100] sci-dev-id:187 [ 1.298310] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: enabled [ 1.305000] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.313561] 40a00000.serial: ttyS1 at MMIO 0x40a00000 (irq = 16, base_baud = 6000000) is a 8250 [ 1.323722] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 24, base_baud = 3000000) is a 8250 [ 1.332510] printk: console [ttyS3] enabled [ 1.332510] printk: console [ttyS3] enabled [ 1.340965] printk: bootconsole [ns16550a0] disabled [ 1.340965] printk: bootconsole [ns16550a0] disabled [ 1.352657] tidss 4a00000.dss: WA for erratum i2000: YUV formats disabled [ 1.374549] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 1.380119] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [ 1.387900] xhci-hcd xhci-hcd.0.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 1.397346] xhci-hcd xhci-hcd.0.auto: irq 180, io mem 0x04010000 [ 1.403712] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.411980] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.419211] usb usb1: Product: xHCI Host Controller [ 1.424092] usb usb1: Manufacturer: Linux 5.10.64 xhci-hcd [ 1.429577] usb usb1: SerialNumber: xhci-hcd.0.auto [ 1.435004] hub 1-0:1.0: USB hub found [ 1.438791] hub 1-0:1.0: 1 port detected [ 1.443029] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 1.448528] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [ 1.456193] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed [ 1.462777] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.471031] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.479300] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.486519] usb usb2: Product: xHCI Host Controller [ 1.491396] usb usb2: Manufacturer: Linux 5.10.64 xhci-hcd [ 1.496879] usb usb2: SerialNumber: xhci-hcd.0.auto [ 1.502171] hub 2-0:1.0: USB hub found [ 1.505955] hub 2-0:1.0: 1 port detected [ 1.513548] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 1.519079] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [ 1.526846] xhci-hcd xhci-hcd.1.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 1.536317] xhci-hcd xhci-hcd.1.auto: irq 182, io mem 0x04030000 [ 1.542643] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.550913] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.558132] usb usb3: Product: xHCI Host Controller [ 1.563010] usb usb3: Manufacturer: Linux 5.10.64 xhci-hcd [ 1.568495] usb usb3: SerialNumber: xhci-hcd.1.auto [ 1.573862] hub 3-0:1.0: USB hub found [ 1.577650] hub 3-0:1.0: 1 port detected [ 1.581866] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 1.587366] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [ 1.595027] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed [ 1.601615] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.609824] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.618088] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.625310] usb usb4: Product: xHCI Host Controller [ 1.630187] usb usb4: Manufacturer: Linux 5.10.64 xhci-hcd [ 1.635670] usb usb4: SerialNumber: xhci-hcd.1.auto [ 1.641006] hub 4-0:1.0: USB hub found [ 1.644791] hub 4-0:1.0: 1 port detected [ 1.652718] mmc1: CQHCI version 5.10 [ 1.652904] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 1.653030] mmc0: CQHCI version 5.10 [ 1.665659] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 1.672712] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 1.678478] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 1.685210] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 1.690981] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 1.691803] mmc0: SDHCI controller on 4fa0000.mmc [4fa0000.mmc] using ADMA 64-bit [ 1.696362] mmc1: SDHCI controller on 4f80000.mmc [4f80000.mmc] using ADMA 64-bit [ 1.707796] usb 1-1: new high-speed USB device number 2 using xhci-hcd [ 1.744922] mmc0: new high speed SDHC card at address 0007 [ 1.751288] mmcblk0: mmc0:0007 SL16G 14.5 GiB [ 1.764533] mmcblk0: p1 p2 p3 [ 1.769343] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc5900 [ 1.776406] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc5900 [ 1.786456] keystone-pcie 5600000.pcie: host bridge /bus@100000/pcie@5600000 ranges: [ 1.794238] keystone-pcie 5600000.pcie: IO 0x0018020000..0x001802ffff -> 0x0000000000 [ 1.802604] keystone-pcie 5600000.pcie: MEM 0x0018030000..0x001fffffff -> 0x0018030000 [ 1.811471] mmc1: Command Queue Engine enabled [ 1.815950] mmc1: new HS200 MMC card at address 0001 [ 1.821684] mmcblk1: mmc1:0001 S0J56X 14.8 GiB [ 1.826530] mmcblk1boot0: mmc1:0001 S0J56X partition 1 31.5 MiB [ 1.832736] mmcblk1boot1: mmc1:0001 S0J56X partition 2 31.5 MiB [ 1.838885] mmcblk1rpmb: mmc1:0001 S0J56X partition 3 4.00 MiB, chardev (245:0) [ 1.864016] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 [ 1.872214] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 1.942954] hub 1-1:1.0: USB hub found [ 1.946790] hub 1-1:1.0: 4 ports detected [ 2.291785] usb 1-1.2: new full-speed USB device number 3 using xhci-hcd [ 2.396694] usb 1-1.2: New USB device found, idVendor=8087, idProduct=0025, bcdDevice= 0.02 [ 2.405049] usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 2.535780] usb 1-1.4: new full-speed USB device number 4 using xhci-hcd [ 2.641430] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00 [ 2.649783] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.657089] usb 1-1.4: Product: CP2102N USB to UART Bridge Controller [ 2.663527] usb 1-1.4: Manufacturer: Silicon Labs [ 2.668231] usb 1-1.4: SerialNumber: 2eb9f9e0c72aea11a191bcfad0cd4f36 [ 2.811565] keystone-pcie 5600000.pcie: Phy link never came up [ 2.817657] keystone-pcie 5600000.pcie: PCI host bridge to bus 0000:00 [ 2.824196] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.829685] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.835864] pci_bus 0000:00: root bus resource [mem 0x18030000-0x1fffffff] [ 2.842775] pci 0000:00:00.0: [104c:b00c] type 01 class 0x060400 [ 2.848795] pci 0000:00:00.0: reg 0x10: [mem 0x05600000-0x056fffff] [ 2.855072] pci 0000:00:00.0: reg 0x38: [mem 0x00000000-0x0000ffff pref] [ 2.861833] pci 0000:00:00.0: supports D1 [ 2.865846] pci 0000:00:00.0: PME# supported from D0 D1 D3hot [ 2.877787] pci 0000:00:00.0: BAR 0: assigned [mem 0x18100000-0x181fffff] [ 2.884586] pci 0000:00:00.0: BAR 6: assigned [mem 0x18030000-0x1803ffff pref] [ 2.891812] pci 0000:00:00.0: PCI bridge to [bus 01-ff] [ 2.898571] ti-udma 285c0000.dma-controller: Channels: 4 (tchan: 2, rchan: 2, gp-rflow: 16) [ 2.908924] ti-udma 31150000.dma-controller: Channels: 24 (tchan: 12, rchan: 12, gp-rflow: 64) [ 2.919947] tidss 4a00000.dss: WA for erratum i2000: YUV formats disabled [ 2.929123] [drm] Initialized tidss 1.0.0 20180215 for 4a00000.dss on minor 0 [ 2.936803] tidss 4a00000.dss: [drm] Cannot find any crtc or sizes [ 2.947900] spi-nor spi7.0: w25q128 (16384 Kbytes) [ 2.952731] 11 cmdlinepart partitions found on MTD device 47040000.spi.0 [ 2.959430] Creating 11 MTD partitions on "47040000.spi.0": [ 2.963812] usb 1-1.1: new high-speed USB device number 5 using xhci-hcd [ 2.965005] 0x000000000000-0x000000080000 : "ospi.tiboot3" [ 2.978605] 0x000000080000-0x000000280000 : "ospi.tispl" [ 2.985164] 0x000000280000-0x000000680000 : "ospi.u-boot" [ 2.991805] 0x000000680000-0x0000006a0000 : "ospi.env" [ 2.998184] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup" [ 3.005179] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw" [ 3.011718] 0x0000007c0000-0x0000007d0000 : "pru0-fw" [ 3.017962] 0x0000007d0000-0x0000007e0000 : "pru1-fw" [ 3.024243] 0x0000007e0000-0x0000007f0000 : "rtu0-fw" [ 3.030502] 0x0000007f0000-0x000000800000 : "rtu1-fw" [ 3.036837] 0x000000800000-0x000001000000 : "ospi.rootfs" [ 3.054504] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 3.060282] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 3.072625] usb 1-1.1: New USB device found, idVendor=0781, idProduct=5581, bcdDevice= 1.00 [ 3.080999] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3.088314] usb 1-1.1: Product: Ultra [ 3.091988] usb 1-1.1: Manufacturer: SanDisk [ 3.096268] usb 1-1.1: SerialNumber: 4C530000290203111590 [ 3.101732] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 3.102699] usb-storage 1-1.1:1.0: USB Mass Storage device detected [ 3.107505] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 3.114203] scsi host0: usb-storage 1-1.1:1.0 [ 3.131465] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 3.137235] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 3.151273] debugfs: Directory 'pd:121' with parent 'pm_genpd' already present! [ 3.158647] debugfs: Directory 'pd:120' with parent 'pm_genpd' already present! [ 3.189574] ALSA device list: [ 3.192567] No soundcards found. [ 3.209207] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null) [ 3.217399] VFS: Mounted root (ext4 filesystem) readonly on device 179:1. [ 3.225248] devtmpfs: mounted [ 3.229478] Freeing unused kernel memory: 1664K [ 3.234201] Run /sbin/init as init process [ 3.972824] NET: Registered protocol family 10 [ 3.978430] Segment Routing with IPv6 [ 4.043097] systemd[1]: systemd 247.3-6 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified) [ 4.067806] systemd[1]: Detected architecture arm64.

Welcome to Debian GNU/Linux 11 (bullseye)!

[ 4.093250] systemd[1]: Set hostname to . [ 4.133483] systemd[1]: Using hardware watchdog 'K3 RTI Watchdog', version 0, device /dev/watchdog [ 4.142506] systemd[1]: Set hardware watchdog to 1min. [ 4.152765] scsi 0:0:0:0: Direct-Access SanDisk Ultra 1.00 PQ: 0 ANSI: 6 [ 4.161841] sd 0:0:0:0: [sda] 60063744 512-byte logical blocks: (30.8 GB/28.6 GiB) [ 4.170534] sd 0:0:0:0: [sda] Write Protect is off [ 4.176097] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 4.426590] systemd[1]: /lib/systemd/system/expand-on-first-boot.service:19: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. [ 4.446894] systemd[1]: /lib/systemd/system/expand-on-first-boot.service:20: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. [ 4.467344] sda: sda1 [ 4.472639] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 4.693185] systemd[1]: Configuration file /etc/systemd/system/autostart.service is marked executable. Please remove executable permission bits. Proceeding anyway. [ 4.798888] systemd[1]: Queued start job for default target Graphical Interface. [ 4.808039] random: systemd: uninitialized urandom read (16 bytes read) [ 4.817981] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 4.839998] random: systemd: uninitialized urandom read (16 bytes read) [ 4.848118] systemd[1]: Created slice system-modprobe.slice. [ OK ] Created slice system-modprobe.slice. [ 4.867958] random: systemd: uninitialized urandom read (16 bytes read) [ 4.876202] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 4.901105] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 4.924303] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ 4.948119] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password R…uests to Wall Directory Watch. [ 4.972052] systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. [ 4.983917] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ 5.004111] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 5.019927] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 5.039885] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.055936] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 5.072706] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 5.088261] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ 5.122034] systemd[1]: Condition check resulted in Journal Audit Socket being skipped. [ 5.131034] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.152632] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.176123] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.196500] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.220449] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 5.240522] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 5.268555] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 5.284525] systemd[1]: Condition check resulted in Kernel Trace File System being skipped. [ 5.298291] systemd[1]: Starting Create list of static device nodes for the current kernel... Starting Create list of st…odes for the current kernel... [ 5.329280] systemd[1]: Starting Load Kernel Module configfs... Starting Load Kernel Module configfs... [ 5.348966] systemd[1]: Starting Load Kernel Module drm... Starting Load Kernel Module drm... [ 5.368993] systemd[1]: Starting Load Kernel Module fuse... Starting Load Kernel Module fuse... [ 5.388738] systemd[1]: Started Nameserver information manager. [ OK ] Started Nameserver information manager. [ 5.404771] fuse: init (API version 7.32) [ 5.422264] systemd[1]: Reached target Network (Pre). [ OK ] Reached target Network (Pre). [ 5.453513] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 5.470668] systemd[1]: Starting Journal Service... Starting Journal Service... [ 5.500177] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 5.521605] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 5.549693] systemd[1]: Starting Coldplug All udev Devices... Starting Coldplug All udev Devices... [ 5.587614] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 5.602858] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null) [ 5.612765] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 5.637006] systemd[1]: Mounted Kernel Debug File System. [ OK ] Mounted Kernel Debug File System. [ 5.661820] systemd[1]: Finished Create list of static device nodes for the current kernel. [ OK ] Finished Create list of st… nodes for the current kernel. [ 5.689492] systemd[1]: modprobe@configfs.service: Succeeded. [ 5.696735] systemd[1]: Finished Load Kernel Module configfs. [ OK ] Finished Load Kernel Module configfs. [ 5.721655] systemd[1]: modprobe@drm.service: Succeeded. [ 5.728526] systemd[1]: Finished Load Kernel Module drm. [ OK ] Finished Load Kernel Module drm. [ 5.753411] systemd[1]: modprobe@fuse.service: Succeeded. [ 5.760381] systemd[1]: Finished Load Kernel Module fuse. [ OK ] Finished Load Kernel Module fuse. [ 5.787494] systemd[1]: Finished Load Kernel Modules. [ OK ] Finished Load Kernel Modules. [ 5.804689] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ OK ] Finished Remount Root and Kernel File Systems. Mounting FUSE Control File System... Mounting Kernel Configuration File System... Starting Expand last partition... Starting Flush Journal to Persistent Storage... Starting Load/Save Random Seed... [ 5.970423] systemd-journald[182]: Received client request to flush runtime journal. Starting Apply Kernel Variables... Starting Create System Users... [ OK ] Mounted FUSE Control File System. [ OK ] Mounted Kernel Configuration File System. [ OK ] Finished Apply Kernel Variables. [ OK ] Finished Create System Users. Starting Create Static Device Nodes in /dev... [ OK ] Finished Coldplug All udev Devices. Starting Helper to synchronize boot up for ifupdown... [ OK ] Finished Helper to synchronize boot up for ifupdown. [ OK ] Finished Create Static Device Nodes in /dev. Starting Rule-based Manage…for Device Events and Files... [ 8.335803] random: crng init done [ 8.339222] random: 7 urandom warning(s) missed due to ratelimiting [ OK ] Finished Flush Journal to Persistent Storage. [ OK ] Finished Load/Save Random Seed. Starting Regenerate sshd host keys... [ OK ] Finished Regenerate sshd host keys. [ OK ] Started Rule-based Manager for Device Events and Files. [ OK ] Found device /dev/ttyS3. [ 12.851554] at24 2-0054: supply vcc not found, using dummy regulator [ 12.860198] at24 2-0054: 1024 byte 24c08 EEPROM, writable, 16 bytes/write [ 12.907807] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000 [ 12.915416] libphy: b032400.mdio: probed [ 12.941241] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867 [ 12.949435] davinci_mdio b032400.mdio: phy[1]: device b032400.mdio:01, driver TI DP83867 [ ] (1 of 2) A start job is running for…and last partition (8s / no limit) [ 13.144676] tidss 4a00000.dss: [drm] Cannot find any crtc or sizes [ 13.476686] pps_core: LinuxPPS API ver. 1 registered [ 13.481730] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 13.556153] PTP clock support registered [ 13.613575] remoteproc remoteproc0: b034000.pru is available [ 13.620987] remoteproc remoteproc1: b004000.rtu is available [ 13.628484] remoteproc remoteproc2: b038000.pru is available [ 13.628782] cal 6f03000.cal: Neither port is configured, no point in staying up [ 13.638573] remoteproc remoteproc3: b006000.rtu is available [ 13.650868] remoteproc remoteproc4: b134000.pru is available [ 13.672369] remoteproc remoteproc5: b104000.rtu is available [ 13.679602] remoteproc remoteproc6: b138000.pru is available [ 13.687189] remoteproc remoteproc7: b106000.rtu is available [ 13.695037] remoteproc remoteproc8: b234000.pru is available [ 13.702112] remoteproc remoteproc9: b204000.rtu is available [ ] (2 of 2) A start job is running for…/by-partlabel/data (8s / 1min 30s) [FAILED] Failed to start Expand last partition. See 'systemctl status expand-on-first-boot.service' for details. [ OK ] Reached target Local File Systems (Pre). [ 13.817447] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: lockstep mode not permitted, force configuring for split-mode [ 13.841398] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000 [ 13.850536] remoteproc remoteproc12: 41000000.r5f is available [ 13.860586] platform 41400000.r5f: assigned reserved memory node r5f-dma-memory@a1000000 [ 13.860967] usbcore: registered new interface driver cp210x [ 13.881909] remoteproc remoteproc12: powering up 41000000.r5f [ 13.887944] remoteproc remoteproc12: Booting fw image am65x-mcu-r5f0_0-fw, size 868 [ 13.898302] remoteproc remoteproc12: remote processor 41000000.r5f is now up [ 13.900892] remoteproc remoteproc13: 41400000.r5f is available [ 13.905955] usbserial: USB Serial support registered for cp210x [ 13.918034] cp210x 1-1.4:1.0: cp210x converter detected [ 13.921750] Bluetooth: Core ver 2.22 [ 13.929107] usb 1-1.4: cp210x converter now attached to ttyUSB0 [ 13.931123] remoteproc remoteproc13: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2 [ 13.943383] NET: Registered protocol family 31 [ 13.946077] remoteproc remoteproc13: powering up 41400000.r5f [ 13.948921] Bluetooth: HCI device and connection manager initialized [ 13.948952] Bluetooth: HCI socket layer initialized [ 13.959724] remoteproc remoteproc13: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2 [ 13.974594] Bluetooth: L2CAP socket layer initialized [ 13.975675] remoteproc remoteproc13: request_firmware failed: -2 [ 13.980699] Bluetooth: SCO socket layer initialized [ OK ] Found device /dev/disk/by-partlabel/data. Mounting Mount /data partition... [ 14.078477] EXT4-fs (mmcblk0p3): VFS: Can't find ext4 filesystem [ 14.089108] usbcore: registered new interface driver btusb [ 14.094781] Bluetooth: hci0: Firmware revision 0.1 build 207 week 35 2021 [FAILED] Failed to mount Mount /data partition. See 'systemctl status data.mount' for details. [ OK ] Created slice system-swupdate\x2dusb.slice. [ OK ] Created slice system-usbmount.slice. Starting Expand last partition... [ 14.665121] pps pps0: new PPS source ptp0 [ 14.672029] pps pps1: new PPS source ptp1 [ 14.706454] icssg-prueth icssg0-eth: TI PRU ethernet driver initialized: dual EMAC mode [ OK ] Listening on Load/Save RF …itch Status /dev/rfkill Watch. [ 15.007728] icssg-prueth icssg0-eth eno1: renamed from eth1 [ 15.030071] icssg-prueth icssg0-eth eno2: renamed from eth0 Starting Load/Save RF Kill Switch Status... [ OK ] Started Load/Save RF Kill Switch Status. [FAILED] Failed to start Expand last partition. See 'systemctl status expand-on-first-boot.service' for details. Mounting Mount /data partition... [ 18.215147] EXT4-fs (mmcblk0p3): VFS: Can't find ext4 filesystem [FAILED] Failed to mount Mount /data partition. See 'systemctl status data.mount' for details. [ OK ] Reached target Local File Systems. Starting Raise network interfaces... Starting Create Volatile Files and Directories... [ OK ] Finished Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ OK ] Finished Update UTMP about System Boot/Shutdown. [ OK ] Finished Raise network interfaces. [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target System Time Set. [ OK ] Reached target System Time Synchronized. [ OK ] Started Daily apt download activities. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Started Periodic ext4 Onli…ata Check for All Filesystems. [ OK ] Started Discard unused blocks once a week. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Listening on SWUpdate socket listener. [ OK ] Listening on UUID daemon activation socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting cc220 Service.... Starting Bluetooth service... [ OK ] Started "IOT2050 Board Configuration". [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Remove Stale Onli…t4 Metadata Check Snapshots... Starting Authorization Manager...[ 19.086236] export_store: invalid GPIO 211

     Starting System Logging Service...

[ OK ] Started Configure status LED. Starting usb media swupdate service... [ OK ] Started SWUpdate daemon. [ OK ] Started swupdate progress service. Starting User Login Management... Starting usbmount@dev-sda.service... [ 19.252716] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) Starting usbmount@dev-sda1.service... Starting WPA supplicant... [ OK ] Started cc220 Service.. [ OK ] Finished Remove Stale Onli…ext4 Metadata Check Snapshots. [ OK ] Started usb media swupdate service. [ OK ] Started System Logging Service. [ OK ] Started User Login Management. [ OK ] Started WPA supplicant. [ OK ] Finished usbmount@dev-sda1.service. [ OK ] Started Authorization Manager. Starting Modem Manager... [ OK ] Started Bluetooth service. [ OK ] Reached target Bluetooth. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenBSD Secure Shell server... Starting Permit User Sessions... [ OK ] Started tcf-agent. [ OK ] Finished Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS3. [ OK ] Reached target Login Prompts. Starting Hostname Service... [ OK ] Started OpenBSD Secure Shell server. [ OK ] Started Hostname Service. [ OK ] Started Modem Manager. [ OK ] Reached target Multi-User System. Starting watchdog daemon... Starting Network Manager Script Dispatcher Service... [ OK ] Started watchdog daemon. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ 22.014807] remoteproc remoteproc2: powering up b038000.pru [ OK ] Finished Update UTMP about System Runlevel Changes. [ OK ] Started Network Manager Script Dispatcher Service. [ 22.050967] remoteproc remoteproc2: Booting fw image ti-pruss/am65x-pru1-prueth-fw.elf, size 17008 [ 22.060059] remoteproc remoteproc2: remote processor b038000.pru is now up [ 22.069685] remoteproc remoteproc3: powering up b006000.rtu [ 22.079035] remoteproc remoteproc3: Booting fw image ti-pruss/am65x-rtu1-prueth-fw.elf, size 15588 [ 22.088145] remoteproc remoteproc3: remote processor b006000.rtu is now up [ 22.096275] TI DP83867 b032400.mdio:01: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b032400.mdio:01, irq=POLL) [ 22.107303] net eno1: started

Debian GNU/Linux 11 iot2050-debian ttyS3

iot2050-debian login: [ 33.879859] tps62363-vout: disabling [ 36.441349] TI DP83867 b032400.mdio:01: Downshift occurred from negotiated speed 1Gbps to actual speed 100Mbps, check cabling! [ 36.452849] IPv6: ADDRCONF(NETDEV_CHANGE): eno1: link becomes ready [ 36.453846] icssg-prueth icssg0-eth eno1: Link is Up - 100Mbps/Full (downshifted) - flow control off

jan-kiszka commented 2 years ago

Looks as if patching of the U-Boot env didn't work out on the first boot of the SWUpdate image. That patching will install the boot path switch. Could you share fw_printentand journalctl -u patch-u-boot-env?

amitsagargowda commented 2 years ago

Please find o/p of both command below.

fw_printenv o/p

root@iot2050-debian:# fw_printenv arch=arm baudrate=115200 board=iot2050 board_a5e=A5E452229880AA04 board_name=IOT2050-ADVANCED board_serial=M5BM8308 board_uuid=7FFD2E1C9B1C4BAC98DEE2B111EB527C boot_a_script=load ${devtype} ${devnum}:${distro_bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr} boot_efi_binary=load ${devtype} ${devnum}:${distro_bootpart} ${kernel_addr_r} efi/boot/bootaa64.efi; if fdt addr ${fdt_addr_r}; then bootefi ${kernel_addr_r} ${fdt_addr_r};else bootefi ${kernel_addr_r} ${fdtcontroladdr};fi boot_efi_bootmgr=if fdt addr ${fdt_addr_r}; then bootefi bootmgr ${fdt_addr_r};else bootefi bootmgr;fi boot_extlinux=sysboot ${devtype} ${devnum}:${distro_bootpart} any ${scriptaddr} ${prefix}${boot_syslinux_conf} boot_fdt=try boot_net_usb_start=usb start boot_pci_enum=pci enum boot_prefixes=/ /boot/ boot_script_dhcp=boot.scr.uimg boot_scripts=boot.scr.uimg boot.scr boot_syslinux_conf=extlinux/extlinux.conf boot_targets=mmc1 mmc0 usb0 usb1 usb2 bootcmd=run distro_bootcmd bootcmd_mmc0=devnum=0; run mmc_boot bootcmd_mmc1=devnum=1; run mmc_boot bootcmd_usb0=devnum=0; run usb_boot bootcmd_usb1=devnum=1; run usb_boot bootcmd_usb2=devnum=2; run usb_boot bootdelay=2 bootm_size=0x10000000 cpu=armv8 distro_bootcmd=for target in ${boottargets}; do run bootcmd${target}; done dtboaddr=0x89000000 efi_dtb_prefixes=/ /dtb/ /dtb/current/ eth1addr=e0:dc:a0:c4:91:df eth2addr=e0:dc:a0:c4:91:de fdt_addr_r=0x88000000 fdtaddr=0x88000000 fdtcontroladdr=fdf00480 fdtfile=ti/k3-am6548-iot2050-advanced.dtb fw_version=2022.01-V01.02.01-78-gbf54a81 kernel_addr_r=0x82000000 load_efi_dtb=load ${devtype} ${devnum}:${distro_bootpart} ${fdt_addr_r} ${prefix}${efi_fdtfile} loadaddr=0x82000000 mlfb=6ES7647-0BA00-1YA2 mmc_boot=if mmc dev ${devnum}; then devtype=mmc; run scan_dev_for_boot_part; fi pxefile_addr_r=0x80100000 ramdisk_addr_r=0x88080000 rdaddr=0x88080000 scan_dev_for_boot=echo Scanning ${devtype} ${devnum}:${distro_bootpart}...; for prefix in ${boot_prefixes}; do run scan_dev_for_extlinux; run scan_dev_for_scripts; done;run scan_dev_for_efi; scan_dev_for_boot_part=part list ${devtype} ${devnum} -bootable devplist; env exists devplist || setenv devplist 1; for distro_bootpart in ${devplist}; do if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; done; setenv devplist scan_dev_for_efi=setenv efi_fdtfile ${fdtfile}; for prefix in ${efi_dtb_prefixes}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${efi_fdtfile}; then run load_efi_dtb; fi;done;run boot_efi_bootmgr;if test -e ${devtype} ${devnum}:${distro_bootpart} efi/boot/bootaa64.efi; then echo Found EFI removable media binary efi/boot/bootaa64.efi; run boot_efi_binary; echo EFI LOAD FAILED: continuing...; fi; setenv efi_fdtfile scan_dev_for_extlinux=if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${boot_syslinux_conf}; then echo Found ${prefix}${boot_syslinux_conf}; run boot_extlinux; echo SCRIPT FAILED: continuing...; fi scan_dev_for_scripts=for script in ${boot_scripts}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${script}; then echo Found U-Boot script ${prefix}${script}; run boot_a_script; echo SCRIPT FAILED: continuing...; fi; done scriptaddr=0x80000000 seboot_version=D01.02.02.02-0-g3af64a90-0x0000 soc=k3 usb_boot=usb start; if usb dev ${devnum}; then devtype=usb; run scan_dev_for_boot_part; fi usb_pgood_delay=900 ustate=1 vendor=siemens root@iot2050-debian:~#

journalctl -u patch-u-boot-env

root@iot2050-debian:/tmp# journalctl -u patch-u-boot-env -- Journal begins at Thu 2022-03-10 04:42:17 UTC, ends at Wed 2022-03-23 07:28:18 UTC. -- Mar 10 04:42:58 iot2050-debian systemd[1]: Starting update u-boot environment for swupdate... Mar 10 04:42:59 iot2050-debian systemctl[454]: Removed /etc/systemd/system/multi-user.target.wants/patch-u-boot-env.service. Mar 10 04:43:00 iot2050-debian systemd[1]: patch-u-boot-env.service: Succeeded. Mar 10 04:43:00 iot2050-debian systemd[1]: Finished update u-boot environment for swupdate. root@iot2050-debian:/tmp#

jan-kiszka commented 2 years ago

Hmm, not sure yet what went wrong. The log says everything was fine, but the environment does contain what https://github.com/siemens/meta-iot2050/blob/master/recipes-bsp/patch-u-boot-env/files/patch-u-boot-env.config would bring in. Do you also get that variable state also when running printenv from the U-Boot shell? If so, please retry from Linux: systemctl start patch-u-boot-env.

gylstorffq commented 2 years ago

ustate was introduced during the update by SWUpdate.

amitsagargowda commented 2 years ago

@jan-kiszka and @gylstorffq I tried all steps mentioned above.

Actually I confirmed that uboot has variable ustate=0. Later i trigerred swupdate at end of successfull swupdate tranfer the ustate was set to 1 . I was expecting that rootfs to trigger rootfs change from rootfs1 to rootfs2 but i still see that it points to rootfs1 only. But now the ustate value is set to 3. I have attached complete log for your reference. What could go wrong here? We are really stuck up here as this is one of the main part of our user story. Could you please assist us here.

iot2050-image-swu-example-iot2050-debian-iot2050.swu tmp TMPDIR=/mnt/usb0/tmp/ swupdate -v -i ./iot2050-image-swu-example-iot2050-debian-iot2050.swudebian:/mnt/usb0# TMPDIR=/mnt/usb0/tmp/ swupdate -v -i ./iot2050-image-swu-example-iot2050-debian-iot2050.swu Swupdate v2021.11.0

Licensed under GPLv2. See source distribution for detailed copyright notices.

[INFO ] : SWUPDATE running : [lua_handlers_init] : Lua handler(s) found. [INFO ] : SWUPDATE running : [print_registered_handlers] : Registered handlers: [INFO ] : SWUPDATE running : [print_registered_handlers] : dummy [INFO ] : SWUPDATE running : [print_registered_handlers] : archive [INFO ] : SWUPDATE running : [print_registered_handlers] : tar [INFO ] : SWUPDATE running : [print_registered_handlers] : uboot [INFO ] : SWUPDATE running : [print_registered_handlers] : bootloader [INFO ] : SWUPDATE running : [print_registered_handlers] : diskformat [INFO ] : SWUPDATE running : [print_registered_handlers] : diskpart [INFO ] : SWUPDATE running : [print_registered_handlers] : uniqueuuid [INFO ] : SWUPDATE running : [print_registered_handlers] : lua [INFO ] : SWUPDATE running : [print_registered_handlers] : raw [INFO ] : SWUPDATE running : [print_registered_handlers] : rawfile [INFO ] : SWUPDATE running : [print_registered_handlers] : rawcopy [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_image [INFO ] : SWUPDATE running : [print_registered_handlers] : rdiff_file [INFO ] : SWUPDATE running : [print_registered_handlers] : readback [INFO ] : SWUPDATE running : [print_registered_handlers] : shellscript [INFO ] : SWUPDATE running : [print_registered_handlers] : preinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : postinstall [INFO ] : SWUPDATE running : [print_registered_handlers] : roundrobin [TRACE] : SWUPDATE running : [listener_create] : got no socket at /mnt/usb0/tmp//swupdateprog from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /mnt/usb0/tmp//swupdateprog [TRACE] : SWUPDATE running : [network_initializer] : Main loop daemon [TRACE] : SWUPDATE running : [listener_create] : got no socket at /mnt/usb0/tmp//sockinstctrl from systemd [TRACE] : SWUPDATE running : [listener_create] : creating socket at /mnt/usb0/tmp//sockinstctrl [TRACE] : SWUPDATE running : [network_thread] : Incoming network request: processing... [INFO ] : SWUPDATE started : Software Update started ! [TRACE] : SWUPDATE running : [network_initializer] : Software update started [WARN ] : SWUPDATE running : [swupdate_create_directory] : Directory scripts/ cannot be created due to : File exists [WARN ] : SWUPDATE running : [swupdate_create_directory] : Directory datadst/ cannot be created due to : File exists [TRACE] : SWUPDATE running : [extract_file_to_tmp] : Found file [TRACE] : SWUPDATE running : [extract_file_to_tmp] : filename sw-description [TRACE] : SWUPDATE running : [extract_file_to_tmp] : size 577 [TRACE] : SWUPDATE running : [get_common_fields] : Version 0.1 [TRACE] : SWUPDATE running : [add_properties] : Found properties for img1.gz: [TRACE] : SWUPDATE running : [add_properties_cb] : Property subtype: image [TRACE] : SWUPDATE running : [_parse_images] : Found compressed Image: img1.gz in device : rootfs1,rootfs2 for handler roundrobin [TRACE] : SWUPDATE running : [extract_files] : Found file [TRACE] : SWUPDATE running : [extract_files] : filename img1.gz [TRACE] : SWUPDATE running : [extract_files] : size 869088818 required [TRACE] : SWUPDATE running : [extract_padding] : Expecting 336 padding bytes at end-of-file [TRACE] : SWUPDATE running : [network_initializer] : Valid image found: copying to FLASH [INFO ] : SWUPDATE running : Installation in progress [TRACE] : SWUPDATE running : [install_single_image] : Found installer for stream img1.gz roundrobin [TRACE] : SWUPDATE running : [notify_helper] : Round Robin Handler: No compiled-in config found, trying /etc/swupdate.handler.ini [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Using '/dev/disk/by-partlabel/rootfs2' as target via 'raw' handler. [TRACE] : SWUPDATE running : [lua_dump_table] : properties subtype = image [TRACE] : SWUPDATE running : [lua_dump_table] : Inserting property subtype = image [INFO ] : SWUPDATE running : [notify_helper] : Round Robin Handler: Setting bootloader environment: ustate=1 [TRACE] : SWUPDATE running : [l_handler_wrapper] : [Lua handler] returned: 0 [INFO ] : SWUPDATE successful ! SWUPDATE successful ! [TRACE] : SWUPDATE running : [network_initializer] : Main thread sleep again ! [INFO ] : No SWUPDATE running : Waiting for requests... [INFO ] : SWUPDATE running : [endupdate] : Swupdate was successful ! root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# root@iot2050-debian:/mnt/usb0# fw_printenv arch=arm baudrate=115200 board=iot2050 board_a5e=A5E452229880AA04 board_name=IOT2050-ADVANCED board_serial=M5BM8308 board_uuid=7FFD2E1C9B1C4BAC98DEE2B111EB527C boot_a_script=load ${devtype} ${devnum}:${distro_bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr} boot_efi_binary=load ${devtype} ${devnum}:${distro_bootpart} ${kernel_addr_r} efi/boot/bootaa64.efi; if fdt addr ${fdt_addr_r}; then bootefi ${kernel_addr_r} ${fdt_addr_r};else bootefi ${kernel_addr_r} ${fdtcontroladdr};fi boot_efi_bootmgr=if fdt addr ${fdt_addr_r}; then bootefi bootmgr ${fdt_addr_r};else bootefi bootmgr;fi boot_extlinux=sysboot ${devtype} ${devnum}:${distro_bootpart} any ${scriptaddr} ${prefix}${boot_syslinux_conf} boot_fdt=try boot_net_usb_start=usb start boot_pci_enum=pci enum boot_prefixes=/ /boot/ boot_script_dhcp=boot.scr.uimg boot_scripts=boot.scr.uimg boot.scr boot_syslinux_conf=extlinux/extlinux.conf boot_targets=mmc1 mmc0 usb0 usb1 usb2 bootcmd=run distro_bootcmd bootcmd_mmc0=devnum=0; run mmc_boot bootcmd_mmc1=devnum=1; run mmc_boot bootcmd_usb0=devnum=0; run usb_boot bootcmd_usb1=devnum=1; run usb_boot bootcmd_usb2=devnum=2; run usb_boot bootdelay=2 bootm_size=0x10000000 cpu=armv8 distro_bootcmd=for target in ${boottargets}; do run bootcmd${target}; done dtboaddr=0x89000000 efi_dtb_prefixes=/ /dtb/ /dtb/current/ eth1addr=e0:dc:a0:c4:91:df eth2addr=e0:dc:a0:c4:91:de fdt_addr_r=0x88000000 fdtaddr=0x88000000 fdtcontroladdr=fdf00480 fdtfile=ti/k3-am6548-iot2050-advanced.dtb fw_version=2022.01-V01.02.01-78-gbf54a81 kernel_addr_r=0x82000000 load_efi_dtb=load ${devtype} ${devnum}:${distro_bootpart} ${fdt_addr_r} ${prefix}${efi_fdtfile} loadaddr=0x82000000 mlfb=6ES7647-0BA00-1YA2 mmc_boot=if mmc dev ${devnum}; then devtype=mmc; run scan_dev_for_boot_part; fi pxefile_addr_r=0x80100000 ramdisk_addr_r=0x88080000 rdaddr=0x88080000 scan_dev_for_boot=echo Scanning ${devtype} ${devnum}:${distro_bootpart}...; for prefix in ${boot_prefixes}; do run scan_dev_for_extlinux; run scan_dev_for_scripts; done;run scan_dev_for_efi; scan_dev_for_boot_part=if env exists sysselect; then run start_watchdog; run update_sysselect; else run scan_dev_for_boot_part_fallback; fi scan_dev_for_boot_part_fallback=part list ${devtype} ${devnum} -bootable devplist; env exists devplist || setenv devplist 1; for distro_bootpart in ${devplist}; do if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; done; setenv devplist scan_dev_for_efi=setenv efi_fdtfile ${fdtfile}; for prefix in ${efi_dtb_prefixes}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${efi_fdtfile}; then run load_efi_dtb; fi;done;run boot_efi_bootmgr;if test -e ${devtype} ${devnum}:${distro_bootpart} efi/boot/bootaa64.efi; then echo Found EFI removable media binary efi/boot/bootaa64.efi; run boot_efi_binary; echo EFI LOAD FAILED: continuing...; fi; setenv efi_fdtfile scan_dev_for_extlinux=if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${boot_syslinux_conf}; then echo Found ${prefix}${boot_syslinux_conf}; run boot_extlinux; echo SCRIPT FAILED: continuing...; fi scan_dev_for_scripts=for script in ${boot_scripts}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${script}; then echo Found U-Boot script ${prefix}${script}; run boot_a_script; echo SCRIPT FAILED: continuing...; fi; done scriptaddr=0x80000000 seboot_version=D01.02.02.02-0-g3af64a90-0x0000 soc=k3 start_watchdog=wdt dev watchdog@40610000 || wdt dev rti@40610000; wdt start 60000 sysselect=1 toggle_active=echo 'Switching active partition ...'; if test $sysselect = 1;then setenv sysselect 2; else setenv sysselect 1; fi; echo Active system is now $sysselect; update_sysselect=if env exists ustate && env exists sysselect; then if test $ustate = 0; then echo SWUpdate: no update pending.; elif test $ustate = 1; then echo SWUpdate: ustate = INSTALLED ; setenv ustate 2; run toggle_active; saveenv; elif test $ustate = 2; then echo SWUpdate: ustate = FAILED; setenv ustate 3; run toggle_active; saveenv; elif test $ustate = 3; then echo SWUpdate: still in FAILED; else echo SWUpdate: invalid ustate; fi; setenv distro_bootpart ${sysselect}; if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; else echo SWUpdate: some variables are missing!; fi usb_boot=usb start; if usb dev ${devnum}; then devtype=usb; run scan_dev_for_boot_part; fi usb_pgood_delay=900 ustate=1 vendor=siemens root@iot2050-debian:/mnt/usb0# reboot Stopping Session c2 of user root. [ OK ] Removed slice system-modprobe.slice. [ OK ] Stopped target Bluetooth. [ OK ] Stopped target Graphical Interface. [ OK ] Stopped target Multi-User System. [ OK ] Stopped target Login Prompts. [ OK ] Stopped target Timers. [ OK ] Stopped Daily apt upgrade and clean activities. [ OK ] Stopped Daily apt download activities. [ OK ] Stopped Periodic ext4 Onli…ata Check for All Filesystems. [ OK ] Stopped Discard unused blocks once a week. [ OK ] Stopped Daily Cleanup of Temporary Directories. [ OK ] Stopped target System Time Synchronized. [ OK ] Stopped target System Time Set. [ OK ] Closed Load/Save RF Kill Switch Status /dev/rfkill Watch. Stopping Modem Manager... Stopping Bluetooth service... Stopping Getty on tty1... Stopping Mosquitto MQTT Broker... Stopping Node-RED... Stopping System Logging Service... Stopping Serial Getty on ttyS3... Stopping OpenBSD Secure Shell server... Stopping Configure status LED... Stopping swupdate progress service... Stopping tcf-agent... [ OK ] Stopped Bluetooth service. [ OK ] Stopped System Logging Service. [ OK ] Stopped swupdate progress service. [ OK ] Stopped Modem Manager. [ OK ] Stopped Node-RED. [ OK ] Stopped tcf-agent. [ OK ] Stopped Getty on tty1. [ OK ] Stopped Serial Getty on ttyS3. [ OK ] Stopped Mosquitto MQTT Broker. [ OK ] Stopped OpenBSD Secure Shell server. [ OK ] Stopped Configure status LED. [ OK ] Stopped Session c1 of user root. [ OK ] Stopped Session c2 of user root. [ OK ] Removed slice system-getty.slice. [ OK ] Removed slice system-serial\x2dgetty.slice. Stopping Authorization Manager... [ OK ] Stopped Regenerate sshd host keys. Stopping SWUpdate daemon... Stopping User Login Management... Stopping Load/Save Random Seed... Stopping User Manager for UID 0... [ OK ] Stopped Authorization Manager. [ OK ] Stopped SWUpdate daemon. [ OK ] Stopped User Manager for UID 0. Stopping User Runtime Directory /run/user/0... [ OK ] Stopped User Login Management. [ OK ] Unmounted /run/user/0. [ OK ] Stopped User Runtime Directory /run/user/0. [ OK ] Stopped Load/Save Random Seed. [ OK ] Removed slice User Slice of UID 0. Stopping Permit User Sessions... [ OK ] Stopped Permit User Sessions. [ OK ] Stopped target Network. [ OK ] Stopped target Remote File Systems. Stopping Network Manager... Stopping Raise network interfaces... Stopping WPA supplicant... [ OK ] Stopped WPA supplicant. [ OK ] Stopped Raise network interfaces. [ OK ] Stopped Network Manager. [ OK ] Stopped target Network (Pre). Stopping D-Bus System Message Bus... [ OK ] Stopped D-Bus System Message Bus. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Removed slice User and Session Slice. [ OK ] Stopped target Sockets. [ OK ] Closed D-Bus System Message Bus Socket. [ OK ] Closed SWUpdate socket listener. [ OK ] Closed Syslog Socket. [ OK ] Closed UUID daemon activation socket. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password …ts to Console Directory Watch. [ OK ] Stopped Forward Password R…uests to Wall Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Load Kernel Modules. Stopping Network Time Synchronization... Stopping Update UTMP about System Boot/Shutdown... [ OK ] Stopped Network Time Synchronization. [ OK ] Stopped Update UTMP about System Boot/Shutdown. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Unmounting /mnt/usb0... [ OK ] Unmounted /mnt/usb0. [ OK ] Stopped target Local File Systems (Pre). [ OK ] Reached target Unmount All Filesystems. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create System Users. [ OK ] Stopped Remount Root and Kernel File Systems. [ OK ] Reached target Shutdown. [ OK ] Reached target Final Step. [ OK ] Finished Reboot. [ OK ] Reached target Reboot. [ 1528.098795] watchdog: watchdog0: nowayout prevents watchdog being stopped! [ 1528.105765] watchdog: watchdog0: watchdog did not stop! [ 1531.700076] systemd-shutdown[1]: Syncing filesystems and block devices. [ 1531.829822] systemd-shutdown[1]: Sending SIGTERM to remaining processes... [ 1531.851025] systemd-journald[182]: Received SIGTERM from PID 1 (systemd-shutdow). [ 1533.055265] systemd-shutdown[1]: Sending SIGKILL to remaining processes... [ 1533.074817] systemd-shutdown[1]: Using hardware watchdog 'K3 RTI Watchdog', version 0, device /dev/watchdog [ 1533.087362] systemd-shutdown[1]: Unmounting file systems. [ 1533.095979] [637]: Remounting '/' read-only in with options '(null)'. [ 1533.253165] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null) [ 1533.287691] systemd-shutdown[1]: All filesystems unmounted. [ 1533.293334] systemd-shutdown[1]: Deactivating swaps. [ 1533.298542] systemd-shutdown[1]: All swaps deactivated. [ 1533.303784] systemd-shutdown[1]: Detaching loop devices. [ 1533.313984] systemd-shutdown[1]: All loop devices detached. [ 1533.319589] systemd-shutdown[1]: Stopping MD devices. [ 1533.325033] systemd-shutdown[1]: All MD devices stopped. [ 1533.330359] systemd-shutdown[1]: Detaching DM devices. [ 1533.335825] systemd-shutdown[1]: All DM devices detached. [ 1533.341238] systemd-shutdown[1]: All filesystems, swaps, loop devices, MD devices and DM devices detached. [ 1533.365534] systemd-shutdown[1]: Syncing filesystems and block devices.

[ 1533.397684] reboot: Restarting system AVS@[1100 1200 1200] SIMATIC IOT2050 SE-Boot Version: D01.02.02.02-0-g3af64a90-0x0000 BuildDate: 20220301 SYSFW ABI: 3.1 [version: 21] [21.5.0--v2021.05 (Terrific Llam]

Board: IOT2050-ADVANCED Serial: M5BM8308 MLFB: 6ES7647-0BA00-1YA2 UUID: 7FFD2E1C9B1C4BAC98DEE2B111EB527C A5E: A5E452229880AA04 MAC[0]: e0-dc-a0-c4-91-df MAC[1]: e0-dc-a0-c4-91-de SKU: SE Loading PK... ok PK count: 00 PK version: 00 SV: 00-00 Security ID 0x5125e53f-0x842e215d Security policy: soft

Warning: 0x50000000 Validating FIT... Loading image atf... Loading image tee... Loading image spl... Loading image k3-am65-iot2050-spl.dtb... NOTICE: BL31: v2.6(release): NOTICE: BL31: Built : 10:38:15, Mar 16 2022 I/TC: I/TC: OP-TEE version: 3.16.0 (gcc version 10.2.1 20210110 (Debian 10.2.1-6)) #1 Wed Mar 16 10:38:15 UTC 2022 aarch64 I/TC: WARNING: This OP-TEE configuration might be insecure! I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html I/TC: Primary CPU initializing I/TC: Primary CPU switching to normal world boot

U-Boot SPL 2022.01-V01.02.01-78-gbf54a81 (Mar 17 2022 - 05:05:01 +0000) SYSFW ABI: 3.1 (firmware rev 0x0015 '21.5.0--v2021.05 (Terrific Llam') Trying to boot from SPI

U-Boot 2022.01-V01.02.01-78-gbf54a81 (Mar 17 2022 - 05:05:01 +0000)

Model: SIMATIC IOT2050 Advanced DRAM: 2 GiB WDT: Not starting watchdog@40610000 MMC: mmc@4f80000: 1, mmc@4fa0000: 0 Loading Environment from SPIFlash... SF: Detected w25q128 with page size 256 Bytes, erase size 64 KiB, total 16 MiB OK In: serial Out: serial Err: serial Net: No ethernet found. Hit any key to stop autoboot: 0 switch to partitions #0, OK mmc1(part 0) is current device k3_r5f_rproc r5f@41000000: LockStep mode not permitted on this device k3_r5f_rproc r5f@41000000: rproc configure failed -22 Starting watchdog timer failed (-22) SWUpdate: ustate = INSTALLED Switching active partition ... Active system is now 2 Saving Environment to SPIFlash... Erasing SPI flash...Writing to SPI flash...done Valid environment: 1 OK No partition table - mmc 1 Couldn't find partition mmc 1:2 switch to partitions #0, OK mmc0 is current device k3_r5f_rproc r5f@41000000: Invalid op: Trying to start secondary core 1 in lockstep mode rti_wdt watchdog@40610000: Failed to load watchdog firmware into remote processor 0 Starting watchdog timer failed (-22) SWUpdate: ustate = FAILED Switching active partition ... Active system is now 1 Saving Environment to SPIFlash... Erasing SPI flash...Writing to SPI flash...done Valid environment: 2 OK Scanning mmc 0:1... Found U-Boot script /boot/boot.scr 557 bytes read in 28 ms (18.6 KiB/s)

Executing script at 80000000

Loading /usr/lib/linux-image-5.10.104-cip3/ti/k3-am6548-iot2050-advanced.dtb... 49172 bytes read in 36 ms (1.3 MiB/s) Loading /boot/vmlinux-5.10.104-cip3... 18298888 bytes read in 768 ms (22.7 MiB/s)

Flattened Device Tree blob at 88000000

Booting using the fdt blob at 0x88000000 Loading Device Tree to 000000008fff0000, end 000000008ffff013 ... OK

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.10.104-cip3 (builder@ed99766b5ce4) (aarch64-linux-gnu-gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2) #1 SMP PREEMPT Wed Mar 23 07:15:31 UTC 2022 [ 0.000000] Machine model: SIMATIC IOT2050 Advanced [ 0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002810000 (options '') [ 0.000000] printk: bootconsole [ns16550a0] enabled [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000080000000-0x00000000ffffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000080000000-0x000000009e7fffff] [ 0.000000] node 0: [mem 0x000000009e800000-0x00000000a21fffff] [ 0.000000] node 0: [mem 0x00000000a2200000-0x00000000ffffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000000ffffffff] [ 0.000000] cma: Reserved 24 MiB at 0x00000000fb800000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 22 pages/cpu s49624 r8192 d32296 u90112 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] CPU features: detected: GIC system register CPU interface [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Kernel command line: root=PARTLABEL=rootfs1 console=ttyS3,115200n8 earlycon=ns16550a,mmio32,0x02810000 rootwait [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 1954356K/2097152K available (11134K kernel code, 1038K rwdata, 4000K rodata, 1664K init, 428K bss, 118220K reserved, 24576K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode [ 0.000000] GICv3: 960 SPIs implemented [ 0.000000] GICv3: 0 Extended SPIs implemented [ 0.000000] GICv3: Distributor has no Range Selector support [ 0.000000] GICv3: 16 PPIs implemented [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000 [ 0.000000] ITS [mem 0x01820000-0x0182ffff] [ 0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS [ 0.000000] ITS@0x0000000001820000: Devices Table too large, reduce ids 20->19 [ 0.000000] ITS@0x0000000001820000: allocated 524288 Devices @80800000 (flat, esz 8, psz 64K, shr 0) [ 0.000000] ITS: using cache flushing for cmd queue [ 0.000000] GICv3: using LPI property table @0x0000000080040000 [ 0.000000] GIC: using cache flushing for LPI property table [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000080050000 [ 0.000000] random: get_random_bytes called from start_kernel+0x318/0x4cc with crng_init=0 [ 0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns [ 0.000006] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns [ 0.008624] Console: colour dummy device 80x25 [ 0.013226] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000) [ 0.023921] pid_max: default: 32768 minimum: 301 [ 0.028769] LSM: Security Framework initializing [ 0.033574] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.041161] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.051345] rcu: Hierarchical SRCU implementation. [ 0.056608] Platform MSI: msi-controller@1820000 domain created [ 0.062911] PCI/MSI: /bus@100000/interrupt-controller@1800000/msi-controller@1820000 domain created [ 0.072521] smp: Bringing up secondary CPUs ... I/TC: Secondary CPU 1 initializing I/TC: Secondary CPU 1 switching to normal world boot I/TC: Secondary CPU 2 initializing I/TC: Secondary CPU 2 switching to normal world boot I/TC: Secondary CPU 3 initializing I/TC: Secondary CPU 3 switching to normal world boot [ 0.086163] Detected VIPT I-cache on CPU1 [ 0.086212] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000 [ 0.086227] GICv3: CPU1: using allocated LPI pending table @0x0000000080060000 [ 0.086294] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.095489] Detected VIPT I-cache on CPU2 [ 0.095533] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000 [ 0.095549] GICv3: CPU2: using allocated LPI pending table @0x0000000080070000 [ 0.095617] CPU2: Booted secondary processor 0x0000000100 [0x410fd034] [ 0.104758] Detected VIPT I-cache on CPU3 [ 0.104783] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000 [ 0.104794] GICv3: CPU3: using allocated LPI pending table @0x0000000080080000 [ 0.104834] CPU3: Booted secondary processor 0x0000000101 [0x410fd034] [ 0.104967] smp: Brought up 1 node, 4 CPUs [ 0.185128] SMP: Total of 4 processors activated. [ 0.189951] CPU features: detected: 32-bit EL0 Support [ 0.195231] CPU features: detected: CRC32 instructions [ 0.216805] CPU: All CPU(s) started at EL2 [ 0.221048] alternatives: patching kernel code [ 0.227045] devtmpfs: initialized [ 0.240103] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.250111] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.258513] pinctrl core: initialized pinctrl subsystem [ 0.265082] NET: Registered protocol family 16 [ 0.271869] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations [ 0.279303] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.287547] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.296316] thermal_sys: Registered thermal governor 'step_wise' [ 0.296850] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.310099] ASID allocator initialised with 65536 entries [ 0.353797] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.360687] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages [ 0.367575] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.374437] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages [ 0.386455] k3-chipinfo 43000014.chipid: Family:AM65X rev:SR1.0 JTAGID[0x0bb5a02f] Detected [ 0.396231] iommu: Default domain type: Translated [ 0.401610] SCSI subsystem initialized [ 0.405695] usbcore: registered new interface driver usbfs [ 0.411352] usbcore: registered new interface driver hub [ 0.416827] usbcore: registered new device driver usb [ 0.422447] mc: Linux media interface: v0.10 [ 0.426858] videodev: Linux video capture interface: v2.00 [ 0.432548] EDAC MC: Ver: 3.0.0 [ 0.437247] Advanced Linux Sound Architecture Driver Initialized. [ 0.444386] clocksource: Switched to clocksource arch_sys_counter [ 0.450876] VFS: Disk quotas dquot_6.6.0 [ 0.454960] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.469523] NET: Registered protocol family 2 [ 0.474232] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 0.483423] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) [ 0.492227] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.500511] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 0.508336] TCP: Hash tables configured (established 16384 bind 16384) [ 0.515232] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.522165] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.529666] NET: Registered protocol family 1 [ 0.534937] RPC: Registered named UNIX socket transport module. [ 0.541033] RPC: Registered udp transport module. [ 0.545859] RPC: Registered tcp transport module. [ 0.550680] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.557287] PCI: CLS 0 bytes, default 64 [ 0.562204] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available [ 0.571952] Initialise system trusted keyrings [ 0.576733] workingset: timestamp_bits=46 max_order=19 bucket_order=0 [ 0.589013] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.595718] NFS: Registering the id_resolver key type [ 0.600932] Key type id_resolver registered [ 0.605218] Key type id_legacy registered [ 0.609422] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.616296] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 0.624134] 9p: Installing v9fs 9p2000 file system support [ 0.683903] Key type asymmetric registered [ 0.688109] Asymmetric key parser 'x509' registered [ 0.693143] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.700861] io scheduler mq-deadline registered [ 0.705520] io scheduler kyber registered [ 0.712150] pinctrl-single 4301c000.pinctrl: 70 pins, size 280 [ 0.718790] pinctrl-single 11c000.pinctrl: 185 pins, size 740 [ 0.724833] pinctrl-single 11c2e8.pinctrl: 9 pins, size 36 [ 0.735482] k3-ringacc 2b800000.ringacc: Failed to get MSI domain [ 0.741846] k3-ringacc 3c000000.ringacc: Failed to get MSI domain [ 0.752438] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled [ 0.777141] brd: module loaded [ 0.789517] loop: module loaded [ 0.796787] tun: Universal TUN/TAP device driver, 1.6 [ 0.802717] igbvf: Intel(R) Gigabit Virtual Function Network Driver [ 0.809143] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 0.815277] sky2: driver version 1.30 [ 0.819624] VFIO - User Level meta-driver version: 0.3 [ 0.826596] usbcore: registered new interface driver usb-storage [ 0.833158] i2c /dev entries driver [ 0.837867] sdhci: Secure Digital Host Controller Interface driver [ 0.844205] sdhci: Copyright(c) Pierre Ossman [ 0.849253] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.856727] ledtrig-cpu: registered to indicate activity on CPUs [ 0.862989] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource [ 0.870615] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... [ 0.878319] optee: probing for conduit method. [ 0.882915] optee: revision 3.16 [ 0.883459] optee: initialized driver [ 0.892234] NET: Registered protocol family 17 [ 0.896974] 9pnet: Installing 9P2000 support [ 0.901417] Key type dns_resolver registered [ 0.906000] registered taskstats version 1 [ 0.910214] Loading compiled-in X.509 certificates [ 0.925449] k3-ringacc 2b800000.ringacc: Failed to get MSI domain [ 0.931935] k3-ringacc 3c000000.ringacc: Failed to get MSI domain [ 0.944128] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource [ 0.951779] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: ABI: 3.1 (firmware rev 0x0015 '21.5.0--v2021.05 (Terrific Llam') [ 1.022815] random: fast init done [ 1.043973] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 1.049964] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 1.057131] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 1.063035] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 1.069865] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 1.075773] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 1.084295] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz [ 1.113493] pca953x 1-0020: supply vcc not found, using dummy regulator [ 1.120431] pca953x 1-0020: using no AI [ 1.125453] pca953x 1-0021: supply vcc not found, using dummy regulator [ 1.132385] pca953x 1-0021: using no AI [ 1.137357] pca953x 1-0025: supply vcc not found, using dummy regulator [ 1.144274] pca953x 1-0025: using no AI [ 1.148939] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 400 kHz [ 1.178165] rtc-pcf8563 2-0051: registered as rtc0 [ 1.183451] rtc-pcf8563 2-0051: setting system clock to 2022-03-24T16:59:50 UTC (1648141190) [ 1.192963] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz [ 1.199670] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz [ 1.206302] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz [ 1.213565] omap_i2c 2030000.i2c: bus 5 rev0.12 at 400 kHz [ 1.220447] ti-sci-intr 42200000.interrupt-controller: Interrupt Router 156 domain created [ 1.229193] ti-sci-intr bus@100000:interrupt-controller@a00000: Interrupt Router 100 domain created [ 1.238678] ti-sci-intr 310e0000.interrupt-controller: Interrupt Router 182 domain created [ 1.247514] ti-sci-inta 33d00000.interrupt-controller: Interrupt Aggregator domain 179 created [ 1.265173] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,32] sci-dev-id:195 [ 1.275080] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: enabled [ 1.281767] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.292772] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:818, gp-rings[304,100] sci-dev-id:187 [ 1.302878] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: enabled [ 1.309566] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.318136] 40a00000.serial: ttyS1 at MMIO 0x40a00000 (irq = 16, base_baud = 6000000) is a 8250 [ 1.328305] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 24, base_baud = 3000000) is a 8250 [ 1.337095] printk: console [ttyS3] enabled [ 1.337095] printk: console [ttyS3] enabled [ 1.345549] printk: bootconsole [ns16550a0] disabled [ 1.345549] printk: bootconsole [ns16550a0] disabled [ 1.357226] tidss 4a00000.dss: WA for erratum i2000: YUV formats disabled [ 1.379221] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 1.384793] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [ 1.392574] xhci-hcd xhci-hcd.0.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 1.402022] xhci-hcd xhci-hcd.0.auto: irq 180, io mem 0x04010000 [ 1.408403] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.416671] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.423903] usb usb1: Product: xHCI Host Controller [ 1.428785] usb usb1: Manufacturer: Linux 5.10.104-cip3 xhci-hcd [ 1.434789] usb usb1: SerialNumber: xhci-hcd.0.auto [ 1.440232] hub 1-0:1.0: USB hub found [ 1.444022] hub 1-0:1.0: 1 port detected [ 1.448262] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 1.453766] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [ 1.461429] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed [ 1.468013] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.476242] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.484512] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.491730] usb usb2: Product: xHCI Host Controller [ 1.496608] usb usb2: Manufacturer: Linux 5.10.104-cip3 xhci-hcd [ 1.502611] usb usb2: SerialNumber: xhci-hcd.0.auto [ 1.507912] hub 2-0:1.0: USB hub found [ 1.511694] hub 2-0:1.0: 1 port detected [ 1.519304] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 1.524835] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [ 1.532627] xhci-hcd xhci-hcd.1.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 1.542076] xhci-hcd xhci-hcd.1.auto: irq 182, io mem 0x04030000 [ 1.548446] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.556714] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.563934] usb usb3: Product: xHCI Host Controller [ 1.568811] usb usb3: Manufacturer: Linux 5.10.104-cip3 xhci-hcd [ 1.574815] usb usb3: SerialNumber: xhci-hcd.1.auto [ 1.580208] hub 3-0:1.0: USB hub found [ 1.583997] hub 3-0:1.0: 1 port detected [ 1.588220] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 1.593719] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [ 1.601382] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed [ 1.607971] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.616173] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.624448] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.631668] usb usb4: Product: xHCI Host Controller [ 1.636546] usb usb4: Manufacturer: Linux 5.10.104-cip3 xhci-hcd [ 1.642550] usb usb4: SerialNumber: xhci-hcd.1.auto [ 1.647878] hub 4-0:1.0: USB hub found [ 1.651663] hub 4-0:1.0: 1 port detected [ 1.659433] mmc1: CQHCI version 5.10 [ 1.659448] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 1.664101] mmc0: CQHCI version 5.10 [ 1.668822] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 1.679024] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 1.684801] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 1.691574] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 1.697336] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 1.704427] usb 1-1: new high-speed USB device number 2 using xhci-hcd [ 1.704486] mmc1: SDHCI controller on 4f80000.mmc [4f80000.mmc] using ADMA 64-bit [ 1.716423] mmc0: SDHCI controller on 4fa0000.mmc [4fa0000.mmc] using ADMA 64-bit [ 1.757516] mmc0: new high speed SDHC card at address 0007 [ 1.763876] mmcblk0: mmc0:0007 SL16G 14.5 GiB [ 1.777276] mmcblk0: p1 p2 p3 [ 1.783548] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc5900 [ 1.790603] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc5900 [ 1.800672] keystone-pcie 5600000.pcie: host bridge /bus@100000/pcie@5600000 ranges: [ 1.808453] keystone-pcie 5600000.pcie: IO 0x0018020000..0x001802ffff -> 0x0000000000 [ 1.816818] keystone-pcie 5600000.pcie: MEM 0x0018030000..0x001fffffff -> 0x0018030000 [ 1.825685] mmc1: Command Queue Engine enabled [ 1.830167] mmc1: new HS200 MMC card at address 0001 [ 1.835890] mmcblk1: mmc1:0001 S0J56X 14.8 GiB [ 1.840727] mmcblk1boot0: mmc1:0001 S0J56X partition 1 31.5 MiB [ 1.846928] mmcblk1boot1: mmc1:0001 S0J56X partition 2 31.5 MiB [ 1.853090] mmcblk1rpmb: mmc1:0001 S0J56X partition 3 4.00 MiB, chardev (245:0) [ 1.856631] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 [ 1.868615] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 1.916214] hub 1-1:1.0: USB hub found [ 1.920056] hub 1-1:1.0: 4 ports detected [ 2.268399] usb 1-1.2: new full-speed USB device number 3 using xhci-hcd [ 2.373398] usb 1-1.2: New USB device found, idVendor=8087, idProduct=0025, bcdDevice= 0.02 [ 2.381751] usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 2.508397] usb 1-1.4: new full-speed USB device number 4 using xhci-hcd [ 2.614125] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00 [ 2.622478] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.629801] usb 1-1.4: Product: CP2102N USB to UART Bridge Controller [ 2.636240] usb 1-1.4: Manufacturer: Silicon Labs [ 2.640944] usb 1-1.4: SerialNumber: 2eb9f9e0c72aea11a191bcfad0cd4f36 [ 2.829841] keystone-pcie 5600000.pcie: Phy link never came up [ 2.835854] keystone-pcie 5600000.pcie: PCI host bridge to bus 0000:00 [ 2.842405] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.847896] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.854081] pci_bus 0000:00: root bus resource [mem 0x18030000-0x1fffffff] [ 2.860998] pci 0000:00:00.0: [104c:b00c] type 01 class 0x060400 [ 2.867018] pci 0000:00:00.0: reg 0x10: [mem 0x05600000-0x056fffff] [ 2.873292] pci 0000:00:00.0: reg 0x38: [mem 0x00000000-0x0000ffff pref] [ 2.880054] pci 0000:00:00.0: supports D1 [ 2.884067] pci 0000:00:00.0: PME# supported from D0 D1 D3hot [ 2.896184] pci 0000:00:00.0: BAR 0: assigned [mem 0x18100000-0x181fffff] [ 2.902985] pci 0000:00:00.0: BAR 6: assigned [mem 0x18030000-0x1803ffff pref] [ 2.910209] pci 0000:00:00.0: PCI bridge to [bus 01-ff] [ 2.917208] ti-udma 285c0000.dma-controller: Channels: 4 (tchan: 2, rchan: 2, gp-rflow: 16) [ 2.927536] ti-udma 31150000.dma-controller: Channels: 24 (tchan: 12, rchan: 12, gp-rflow: 64) [ 2.938573] tidss 4a00000.dss: WA for erratum i2000: YUV formats disabled [ 2.947763] [drm] Initialized tidss 1.0.0 20180215 for 4a00000.dss on minor 0 [ 2.955430] tidss 4a00000.dss: [drm] Cannot find any crtc or sizes [ 2.966573] spi-nor spi7.0: w25q128 (16384 Kbytes) [ 2.968446] usb 1-1.1: new high-speed USB device number 5 using xhci-hcd [ 2.971430] 8 fixed-partitions partitions found on MTD device 47040000.spi.0 [ 2.985106] Creating 8 MTD partitions on "47040000.spi.0": [ 2.990590] 0x000000000000-0x000000180000 : "seboot" [ 2.997053] 0x000000180000-0x000000380000 : "tispl" [ 3.003258] 0x000000380000-0x000000680000 : "u-boot" [ 3.009520] 0x000000680000-0x0000006a0000 : "env" [ 3.015542] 0x0000006a0000-0x0000006c0000 : "env.backup" [ 3.022097] 0x0000006c0000-0x0000006d0000 : "otpcmd" [ 3.028321] 0x0000006d0000-0x000000e80000 : "unused" [ 3.034545] 0x000000e80000-0x000001000000 : "seboot.backup" [ 3.052369] davinci-mcasp 2b00000.mcasp: invalid tdm slots: 0 [ 3.058169] davinci-mcasp 2b00000.mcasp: IRQ common not found [ 3.072196] davinci-mcasp 2b10000.mcasp: invalid tdm slots: 0 [ 3.077231] usb 1-1.1: New USB device found, idVendor=0781, idProduct=5581, bcdDevice= 1.00 [ 3.077968] davinci-mcasp 2b10000.mcasp: IRQ common not found [ 3.086297] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3.086307] usb 1-1.1: Product: Ultra [ 3.103072] usb 1-1.1: Manufacturer: SanDisk [ 3.107354] usb 1-1.1: SerialNumber: 4C530000290203111590 [ 3.113884] usb-storage 1-1.1:1.0: USB Mass Storage device detected [ 3.120646] scsi host0: usb-storage 1-1.1:1.0 [ 3.130152] davinci-mcasp 2b20000.mcasp: invalid tdm slots: 0 [ 3.135925] davinci-mcasp 2b20000.mcasp: IRQ common not found [ 3.149973] debugfs: Directory 'pd:121' with parent 'pm_genpd' already present! [ 3.157355] debugfs: Directory 'pd:120' with parent 'pm_genpd' already present! [ 3.187553] ALSA device list: [ 3.190539] No soundcards found. [ 3.206958] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null) [ 3.215168] VFS: Mounted root (ext4 filesystem) readonly on device 179:1. [ 3.222909] devtmpfs: mounted [ 3.227124] Freeing unused kernel memory: 1664K [ 3.231850] Run /sbin/init as init process [ 3.936155] NET: Registered protocol family 10 [ 3.941742] Segment Routing with IPv6 [ 4.002455] systemd[1]: systemd 247.3-6 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified) [ 4.027161] systemd[1]: Detected architecture arm64.

Welcome to Debian GNU/Linux 11 (bullseye)!

[ 4.053639] systemd[1]: Set hostname to . [ 4.093705] systemd[1]: Using hardware watchdog 'K3 RTI Watchdog', version 0, device /dev/watchdog [ 4.102768] systemd[1]: Set hardware watchdog to 1min. [ 4.133577] scsi 0:0:0:0: Direct-Access SanDisk Ultra 1.00 PQ: 0 ANSI: 6 [ 4.143107] sd 0:0:0:0: [sda] 60063744 512-byte logical blocks: (30.8 GB/28.6 GiB) [ 4.152043] sd 0:0:0:0: [sda] Write Protect is off [ 4.159934] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 4.344256] sda: sda1 [ 4.350111] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 4.579075] systemd[1]: /lib/systemd/system/expand-on-first-boot.service:19: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. [ 4.599398] systemd[1]: /lib/systemd/system/expand-on-first-boot.service:20: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. [ 4.759751] systemd[1]: Queued start job for default target Graphical Interface. [ 4.768862] random: systemd: uninitialized urandom read (16 bytes read) [ 4.778875] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 4.800634] random: systemd: uninitialized urandom read (16 bytes read) [ 4.808814] systemd[1]: Created slice system-modprobe.slice. [ OK ] Created slice system-modprobe.slice. [ 4.828564] random: systemd: uninitialized urandom read (16 bytes read) [ 4.836870] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 4.861635] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 4.884890] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ 4.908761] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password R…uests to Wall Directory Watch. [ 4.932655] systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. [ 4.944494] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ 4.964733] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 4.980553] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 5.000517] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.016553] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 5.033264] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 5.048870] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ 5.082902] systemd[1]: Condition check resulted in Journal Audit Socket being skipped. [ 5.091852] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.113232] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.137261] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.161085] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.185132] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 5.205337] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 5.233029] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 5.249121] systemd[1]: Condition check resulted in Kernel Trace File System being skipped. [ 5.262801] systemd[1]: Starting Create list of static device nodes for the current kernel... Starting Create list of st…odes for the current kernel... [ 5.293977] systemd[1]: Starting Load Kernel Module configfs... Starting Load Kernel Module configfs... [ 5.313549] systemd[1]: Starting Load Kernel Module drm... Starting Load Kernel Module drm... [ 5.333675] systemd[1]: Starting Load Kernel Module fuse... Starting Load Kernel Module fuse... [ 5.353347] systemd[1]: Started Nameserver information manager. [ OK ] Started Nameserver information mana[ 5.363457] fuse: init (API version 7.32) ger. [ 5.391104] systemd[1]: Reached target Network (Pre). [ OK ] Reached target Network (Pre). [ 5.422188] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 5.439342] systemd[1]: Starting Journal Service... Starting Journal Service... [ 5.464466] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 5.486149] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 5.514060] systemd[1]: Starting Coldplug All udev Devices... Starting Coldplug All udev Devices... [ 5.539317] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 5.567968] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null) [ 5.568859] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 5.600914] systemd[1]: Mounted Kernel Debug File System. [ OK ] Mounted Kernel Debug File System. [ 5.626540] systemd[1]: Finished Create list of static device nodes for the current kernel. [ OK ] Finished Create list of st… nodes for the current kernel. [ 5.658342] systemd[1]: modprobe@configfs.service: Succeeded. [ 5.665598] systemd[1]: Finished Load Kernel Module configfs. [ OK ] Finished Load Kernel Module configfs. [ 5.690188] systemd[1]: modprobe@drm.service: Succeeded. [ 5.697065] systemd[1]: Finished Load Kernel Module drm. [ OK ] Finished Load Kernel Module drm. [ 5.722192] systemd[1]: modprobe@fuse.service: Succeeded. [ 5.729197] systemd[1]: Finished Load Kernel Module fuse. [ OK ] Finished Load Kernel Module fuse. [ 5.755957] systemd[1]: Finished Load Kernel Modules. [ OK ] Finished Load Kernel Modules. [ 5.778229] systemd[1]: Finished Remount Root and Kernel File Systems. [ OK ] Finished Remount Root and Kernel File Systems. [ 5.801156] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. Mounting FUSE Control File System... Mounting Kernel Configuration File System... Starting Expand last partition... Starting Flush Journal to Persistent Storage... Starting Load/Save Random Seed... [ 5.938820] systemd-journald[183]: Received client request to flush runtime journal. Starting Apply Kernel Variables... Starting Create System Users... [ OK ] Mounted FUSE Control File System. [ OK ] Mounted Kernel Configuration File System. [ OK ] Finished Apply Kernel Variables. [ OK ] Finished Create System Users. Starting Create Static Device Nodes in /dev... [ OK ] Finished Coldplug All udev Devices. Starting Helper to synchronize boot up for ifupdown... [ OK ] Finished Helper to synchronize boot up for ifupdown. [ OK ] Finished Create Static Device Nodes in /dev. Starting Rule-based Manage…for Device Events and Files... [ OK ] Finished Flush Journal to Persistent Storage. [ OK ] Started Rule-based Manager for Device Events and Files. [ 8.260451] random: crng init done [ 8.263874] random: 7 urandom warning(s) missed due to ratelimiting [ OK ] Found device /dev/ttyS3. [ 10.627697] at24 2-0054: supply vcc not found, using dummy regulator [ 10.635703] at24 2-0054: 1024 byte 24c08 EEPROM, writable, 16 bytes/write [ OK ] Finished Load/Save Random Seed. Starting Regenerate sshd host keys... [ 10.740437] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000 [ 10.770955] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867 [ 10.779140] davinci_mdio b032400.mdio: phy[1]: device b032400.mdio:01, driver TI DP83867 [ 11.337914] pps_core: LinuxPPS API ver. 1 registered [ 11.342941] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 11.461314] PTP clock support registered [ 11.469003] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: lockstep mode not permitted, force configuring for split-mode [ 11.481317] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000 [ 11.498597] remoteproc remoteproc0: 41000000.r5f is available [ 11.508733] platform 41400000.r5f: assigned reserved memory node r5f-dma-memory@a1000000 [ 11.517497] remoteproc remoteproc1: 41400000.r5f is available [ 11.548050] remoteproc remoteproc2: b034000.pru is available [ 11.556278] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2 [ 11.560192] remoteproc remoteproc3: b004000.rtu is available [ 11.566135] remoteproc remoteproc1: powering up 41400000.r5f [ 11.575546] remoteproc remoteproc4: b038000.pru is available [ 11.577260] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2 [ 11.593506] remoteproc remoteproc1: request_firmware failed: -2 [ 11.601069] remoteproc remoteproc5: b006000.rtu is available [ 11.609604] remoteproc remoteproc6: b134000.pru is available [ 11.618447] remoteproc remoteproc7: b104000.rtu is available [ 11.626049] remoteproc remoteproc8: b138000.pru is available [ 11.633194] remoteproc remoteproc9: b106000.rtu is available [ 11.640566] remoteproc remoteproc10: b234000.pru is available [ 11.646429] cal 6f03000.cal: Neither port is configured, no point in staying up [ 11.647796] remoteproc remoteproc11: b204000.rtu is available [ 11.653803] remoteproc remoteproc0: powering up 41000000.r5f [ 11.661038] remoteproc remoteproc12: b238000.pru is available [ 11.665270] remoteproc remoteproc0: Booting fw image am65x-mcu-r5f0_0-fw, size 868 [ 11.679567] remoteproc remoteproc0: remote processor 41000000.r5f is now up [ 11.680542] remoteproc remoteproc13: b206000.rtu is available [ 11.744578] usbcore: registered new interface driver cp210x [ 11.750281] usbserial: USB Serial support registered for cp210x [ 11.758243] cp210x 1-1.4:1.0: cp210x converter detected [ 11.785115] usb 1-1.4: cp210x converter now attached to ttyUSB0 [FAILED] Failed to start Expand last partition. See 'systemctl status expand-on-first-boot.service' for details. [ 11.898956] Bluetooth: Core ver 2.22 [ 11.928912] NET: Registered protocol family 31 [ 11.933774] Bluetooth: HCI device and connection manager initialized [ OK ] Finished Regenerate sshd host keys Bluetooth: HCI socket layer initialized [0m. [ 11.959840] Bluetooth: L2CAP socket layer initialized [ 11.965022] Bluetooth: SCO socket layer initialized [ OK ] Found device /dev/disk/by-partlabel/data. [ 12.071831] usbcore: registered new interface driver btusb [ 12.074322] Bluetooth: hci0: Firmware revision 0.1 build 168 week 48 2020 [ OK ] Reached target Local File Systems (Pre). Mounting Mount /data partition... [ 12.301948] EXT4-fs: Warning: mounting with data=journal disables delayed allocation, dioread_nolock, O_DIRECT and fast_commit support! [ 12.350419] EXT4-fs (mmcblk0p3): mounted filesystem with journalled data mode. Opts: data=journal,nodelalloc,commit=20 [ OK ] Mounted Mount /data partition.[ 12.373645] pps pps0: new PPS source ptp0

[ 12.381158] pps pps1: new PPS source ptp1 [ 12.418899] icssg-prueth icssg0-eth: TI PRU ethernet driver initialized: dual EMAC mode [ OK ] Reached target Local File Systems. Starting Raise network interfaces... Starting Create Volatile Files and Directories... [ OK ] Listening on Load/Save RF …itch Status /dev/rfkill Watch. [ 12.620894] icssg-prueth icssg0-eth eno2: renamed from eth0 Starting Expand last partition... [ 12.671157] icssg-prueth icssg0-eth eno1: renamed from eth1 Starting Load/Save RF Kill Switch Status... [ OK ] Finished Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Load/Save RF Kill Switch Status. [ OK ] Finished Update UTMP about System Boot/Shutdown. [ OK ] Finished Raise network interfaces. [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary [ 13.097316] tidss 4a00000.dss: [drm] Cannot find any crtc or sizes Directories. [ OK ] Reached target System Time Set. [ OK ] Reached target System Time Synchronized. [ OK ] Started Daily apt download activities. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Started Periodic ext4 Onli…ata Check for All Filesystems. [ OK ] Started Discard unused blocks once a week. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Listening on SWUpdate socket listener. [ OK ] Listening on UUID daemon activation socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Bluetooth service... [ OK ] Started "IOT2050 Board Configuration". [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Remove Stale Onli…t4 Metadata Check Snapshots... Starting Authorization Manager... Starting System Logging Service... [ OK ] Started Configure status LED. [ OK ] Started SWUpdate daemon. [ OK ] Started swupdate progress service. Starting User Login Management... Starting WPA supplicant... [ 21.342103] EXT4-fs (mmcblk0p3): resizing filesystem from 131072 to 1692411 blocks [ 32.626156] EXT4-fs (mmcblk0p3): resized to 1048576 blocks [ 33.097178] EXT4-fs (mmcblk0p3): resized filesystem to 1692411 [ 33.828425] tps62363-vout: disabling [ 46.108879] remoteproc remoteproc4: powering up b038000.pru [ 46.134712] remoteproc remoteproc4: Booting fw image ti-pruss/am65x-pru1-prueth-fw.elf, size 17008 [ 46.143972] remoteproc remoteproc4: remote processor b038000.pru is now up [ 46.152932] remoteproc remoteproc5: powering up b006000.rtu [ 46.175346] remoteproc remoteproc5: Booting fw image ti-pruss/am65x-rtu1-prueth-fw.elf, size 15588 [ 46.188532] remoteproc remoteproc5: remote processor b006000.rtu is now up [ 46.200189] TI DP83867 b032400.mdio:01: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b032400.mdio:01, irq=POLL) [ 46.220771] net eno1: started [ 46.225208] TI DP83867 b032400.mdio:01: Downshift occurred from negotiated speed 1Gbps to actual speed 100Mbps, check cabling! [ 46.240082] IPv6: ADDRCONF(NETDEV_CHANGE): eno1: link becomes ready [ 46.247974] icssg-prueth icssg0-eth eno1: Link is Up - 100Mbps/Full (downshifted) - flow control off [ 46.328840] remoteproc remoteproc2: powering up b034000.pru [ 46.342168] remoteproc remoteproc2: Booting fw image ti-pruss/am65x-pru0-prueth-fw.elf, size 16992 [ 46.352199] remoteproc remoteproc2: remote processor b034000.pru is now up [ 46.359476] remoteproc remoteproc3: powering up b004000.rtu [ 46.368553] remoteproc remoteproc3: Booting fw image ti-pruss/am65x-rtu0-prueth-fw.elf, size 15588 [ 46.377667] remoteproc remoteproc3: remote processor b004000.rtu is now up [ 46.386350] TI DP83867 b032400.mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b032400.mdio:00, irq=POLL) [ 46.400609] net eno2: started

Debian GNU/Linux 11 iot2050-debian ttyS3

iot2050-debian login: [ OK ] Started Mosquitto MQTT Broker. [ OK ] Started OpenBSD Secure Shell server. [ OK ] Finished Expand last partition. [ OK ] Started Modem Manager. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Network Manager Script Dispatcher Service... Starting Update UTMP about System Runlevel Changes... [ OK ] Finished Update UTMP about System Runlevel Changes. [ OK ] Started Network Manager Script Dispatcher Service.

iot2050-debian login: iot2050-debian login: iot2050-debian login: root Password: Linux iot2050-debian 5.10.104-cip3 #1 SMP PREEMPT Wed Mar 23 07:15:31 UTC 2022 aarch64

The programs included with the Debian GNU/Linux system are free software; the exact distribution terms for each program are described in the individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. Last login: Thu Mar 24 16:44:24 UTC 2022 from 192.168.200.120 on pts/0 root@iot2050-debian:# fw_printenv arch=arm baudrate=115200 board=iot2050 board_a5e=A5E452229880AA04 board_name=IOT2050-ADVANCED board_serial=M5BM8308 board_uuid=7FFD2E1C9B1C4BAC98DEE2B111EB527C boot_a_script=load ${devtype} ${devnum}:${distro_bootpart} ${scriptaddr} ${prefix}${script}; source ${scriptaddr} boot_efi_binary=load ${devtype} ${devnum}:${distro_bootpart} ${kernel_addr_r} efi/boot/bootaa64.efi; if fdt addr ${fdt_addr_r}; then bootefi ${kernel_addr_r} ${fdt_addr_r};else bootefi ${kernel_addr_r} ${fdtcontroladdr};fi boot_efi_bootmgr=if fdt addr ${fdt_addr_r}; then bootefi bootmgr ${fdt_addr_r};else bootefi bootmgr;fi boot_extlinux=sysboot ${devtype} ${devnum}:${distro_bootpart} any ${scriptaddr} ${prefix}${boot_syslinux_conf} boot_fdt=try boot_net_usb_start=usb start boot_pci_enum=pci enum boot_prefixes=/ /boot/ boot_script_dhcp=boot.scr.uimg boot_scripts=boot.scr.uimg boot.scr boot_syslinux_conf=extlinux/extlinux.conf boot_targets=mmc1 mmc0 usb0 usb1 usb2 bootcmd=run distro_bootcmd bootcmd_mmc0=devnum=0; run mmc_boot bootcmd_mmc1=devnum=1; run mmc_boot bootcmd_usb0=devnum=0; run usb_boot bootcmd_usb1=devnum=1; run usb_boot bootcmd_usb2=devnum=2; run usb_boot bootdelay=2 bootm_size=0x10000000 cpu=armv8 distro_bootcmd=for target in ${boottargets}; do run bootcmd${target}; done distro_bootpart=2 dtboaddr=0x89000000 efi_dtb_prefixes=/ /dtb/ /dtb/current/ eth1addr=e0:dc:a0:c4:91:df eth2addr=e0:dc:a0:c4:91:de fdt_addr_r=0x88000000 fdtaddr=0x88000000 fdtcontroladdr=fdf00480 fdtfile=ti/k3-am6548-iot2050-advanced.dtb fw_version=2022.01-V01.02.01-78-gbf54a81 kernel_addr_r=0x82000000 load_efi_dtb=load ${devtype} ${devnum}:${distro_bootpart} ${fdt_addr_r} ${prefix}${efi_fdtfile} loadaddr=0x82000000 mlfb=6ES7647-0BA00-1YA2 mmc_boot=if mmc dev ${devnum}; then devtype=mmc; run scan_dev_for_boot_part; fi pxefile_addr_r=0x80100000 ramdisk_addr_r=0x88080000 rdaddr=0x88080000 scan_dev_for_boot=echo Scanning ${devtype} ${devnum}:${distro_bootpart}...; for prefix in ${boot_prefixes}; do run scan_dev_for_extlinux; run scan_dev_for_scripts; done;run scan_dev_for_efi; scan_dev_for_boot_part=if env exists sysselect; then run start_watchdog; run update_sysselect; else run scan_dev_for_boot_part_fallback; fi scan_dev_for_boot_part_fallback=part list ${devtype} ${devnum} -bootable devplist; env exists devplist || setenv devplist 1; for distro_bootpart in ${devplist}; do if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; done; setenv devplist scan_dev_for_efi=setenv efi_fdtfile ${fdtfile}; for prefix in ${efi_dtb_prefixes}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${efi_fdtfile}; then run load_efi_dtb; fi;done;run boot_efi_bootmgr;if test -e ${devtype} ${devnum}:${distro_bootpart} efi/boot/bootaa64.efi; then echo Found EFI removable media binary efi/boot/bootaa64.efi; run boot_efi_binary; echo EFI LOAD FAILED: continuing...; fi; setenv efi_fdtfile scan_dev_for_extlinux=if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${boot_syslinux_conf}; then echo Found ${prefix}${boot_syslinux_conf}; run boot_extlinux; echo SCRIPT FAILED: continuing...; fi scan_dev_for_scripts=for script in ${boot_scripts}; do if test -e ${devtype} ${devnum}:${distro_bootpart} ${prefix}${script}; then echo Found U-Boot script ${prefix}${script}; run boot_a_script; echo SCRIPT FAILED: continuing...; fi; done scriptaddr=0x80000000 seboot_version=D01.02.02.02-0-g3af64a90-0x0000 soc=k3 start_watchdog=wdt dev watchdog@40610000 || wdt dev rti@40610000; wdt start 60000 sysselect=1 toggle_active=echo 'Switching active partition ...'; if test $sysselect = 1;then setenv sysselect 2; else setenv sysselect 1; fi; echo Active system is now $sysselect; update_sysselect=if env exists ustate && env exists sysselect; then if test $ustate = 0; then echo SWUpdate: no update pending.; elif test $ustate = 1; then echo SWUpdate: ustate = INSTALLED ; setenv ustate 2; run toggle_active; saveenv; elif test $ustate = 2; then echo SWUpdate: ustate = FAILED; setenv ustate 3; run toggle_active; saveenv; elif test $ustate = 3; then echo SWUpdate: still in FAILED; else echo SWUpdate: invalid ustate; fi; setenv distro_bootpart ${sysselect}; if fstype ${devtype} ${devnum}:${distro_bootpart} bootfstype; then run scan_dev_for_boot; fi; else echo SWUpdate: some variables are missing!; fi usb_boot=usb start; if usb dev ${devnum}; then devtype=usb; run scan_dev_for_boot_part; fi usb_pgood_delay=900 ustate=3 vendor=siemens root@iot2050-debian:# mount /dev/mmcblk0p1 on / type ext4 (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,relatime,size=977176k,nr_inodes=244294,mode=755) proc on /proc type proc (rw,nosuid,nodev,noexec,relatime) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev) devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,size=396120k,nr_inodes=819200,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) cgroup2 on /sys/fs/cgroup type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) hugetlbfs on /dev/hugepages type hugetlbfs (rw,relatime,pagesize=2M) mqueue on /dev/mqueue type mqueue (rw,nosuid,nodev,noexec,relatime) debugfs on /sys/kernel/debug type debugfs (rw,nosuid,nodev,noexec,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,nosuid,nodev,noexec,relatime) configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime) /dev/mmcblk0p3 on /data type ext4 (rw,noatime,nodiratime,nodioread_nolock,nodelalloc,commit=20,data=journal) tmpfs on /run/user/0 type tmpfs (rw,nosuid,nodev,relatime,size=198056k,nr_inodes=49514,mode=700) root@iot2050-debian:~#

jan-kiszka commented 2 years ago

Are you using the eMMC or the SD card as mass storage for the device? It looks to me like you have both plugged and U-Boot configured to prefer the SD card. The result is a mess when both are bootable.

amitsagargowda commented 2 years ago

@jan-kiszka : We are using 16GB sd card as mass storage device. Yes currently both are plugged in and we prefer SD card out of it. Is there a way to make emmc not bootable?

jan-kiszka commented 2 years ago

https://github.com/siemens/meta-iot2050/#selecting-a-boot-device

amitsagargowda commented 2 years ago

@jan-kiszka : I removed sd card and tried on emmc the swupdate now works fine thanks a lot. But we need SD card approach also. Currently my boot_targets is set to boot_targets=mmc1 mmc0 usb0 usb1 usb2 If i understand the boot order is emmc, sdcard and USBs right?

Is this creating the issue?

If i set boot_targets=mmc0 mmc1 usb0 usb1 usb2

Will it help

What about setting boot_targets=mmc0

what is your thought on this?

jan-kiszka commented 2 years ago

Yes, mmc0 is SD, mmc1 is eMMC. If you move mmc0 to the front, you will only boot from eMMC if SD fails. If you drop everything but SD from boot_targets, only that will be tried for booting.

siemens / meta-iot2050

Iot2050 Firmware update via swupdate fails #280

[ 3366.860335] reboot: Restarting system AVS@[1100 1200 1200] SIMATIC IOT2050 SE-Boot Version: D01.02.02.02-0-g3af64a90-0x0000 BuildDate: 20220301 SYSFW ABI: 3.1 [version: 21] [21.5.0--v2021.05 (Terrific Llam]

Executing script at 80000000

Flattened Device Tree blob at 88000000

fw_printenv o/p

journalctl -u patch-u-boot-env

[ 1533.397684] reboot: Restarting system AVS@[1100 1200 1200] SIMATIC IOT2050 SE-Boot Version: D01.02.02.02-0-g3af64a90-0x0000 BuildDate: 20220301 SYSFW ABI: 3.1 [version: 21] [21.5.0--v2021.05 (Terrific Llam]

Executing script at 80000000

Flattened Device Tree blob at 88000000