Hi,

I have a problem loading the CTAG Face driver, the CTAG Face was split from a CTAG Beast purchase. The CTAG Beast was purchased from Bela shop in around 2022.

First, I insert the CTAG Face on top of Beaglebone Green's GPIO in the correct direction. The BBG has a serial cable connected to a Windows 10 computer. I boot it up with release v0.1.2 downloaded from https://github.com/ctag-fh-kiel/bela-ctag/releases, the console startup messages look ok to me with some error message which I am not sure. After login as debian user, I try to run "sudo ./load-ctag-face-8ch-drivers.sh", but encountered the following error message then it stuck there and never returned, Ctrl-C does not stop the script too,

./load-ctag-face-8ch-drivers.sh: line 2: echo: write error: Device or resource busy

Please note that this CTAG Face was working properly. Right before the mentioned test as stated above, it was inserted in a different BBG and worked perfectly. That BBG was another build of BBG which eMMC has our audio software inside and CTAG Face is functioning with the correct input audio and output audio. And that BBG audio software runs on top the same v0.1.2 downloaded from https://github.com/ctag-fh-kiel/bela-ctag/releases. We md5 checked these two images and they are identical, therefore, the problem look strange to us.

Here is more details,

===

I begin with this webpage,
https://learn.bela.io/products/multichannel/ctag-multichannel-board/

Read,
https://github.com/ctag-fh-kiel/bela-ctag/wiki

Set jumper according to,
https://learn.bela.io/using-bela/technical-explainers/configuring-ctag/

And here is the serial console bootup message,

U-Boot SPL 2018.01-00002-gbbdeb63-dirty (May 08 2018 - 15:14:23)
Trying to boot from MMC2


U-Boot 2018.01-00002-gbbdeb63-dirty (May 08 2018 - 15:14:23 +0800)

CPU  : AM335X-GP rev 2.1
I2C:   ready
DRAM:  512 MiB
No match for driver 'omap_hsmmc'
No match for driver 'omap_hsmmc'
Some drivers were not found
Reset Source: Power-on reset has occurred.
TPS65217: PGDLY 20 ms -> 400 ms
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
Using default environment

Board: BeagleBone Black
<ethaddr> not set. Validating first E-fuse MAC
BeagleBone Black:
Model: SeeedStudio BeagleBone Green:
BeagleBone: cape eeprom: i2c_probe: 0x54:
BeagleBone: cape eeprom: i2c_probe: 0x55:
BeagleBone: cape eeprom: i2c_probe: 0x56:
BeagleBone: cape eeprom: i2c_probe: 0x57:
Net:   eth0: MII MODE
cpsw, usb_ether
Press SPACE to abort autoboot in 2 seconds
board_name=[BBG1] ...
switch to partitions #0, OK
mmc0 is current device
SD/MMC found on device 0
** Bad device 0:2 0x82000000 **
** Bad device 0:2 0x82000000 **
switch to partitions #0, OK
mmc0 is current device
Scanning mmc 0:1...
gpio: pin 56 (gpio 56) value is 0
gpio: pin 55 (gpio 55) value is 0
gpio: pin 54 (gpio 54) value is 0
gpio: pin 53 (gpio 53) value is 1
switch to partitions #0, OK
mmc0 is current device
gpio: pin 54 (gpio 54) value is 1
Checking for: /uEnv.txt ...
Checking for: /boot.scr ...
Checking for: /boot/boot.scr ...
Checking for: /boot/uEnv.txt ...
gpio: pin 55 (gpio 55) value is 1
2145 bytes read in 40 ms (51.8 KiB/s)
Loaded environment from /boot/uEnv.txt
debug: [dtb=am335x-boneblack-ctag-face.dtb] ...
Using: dtb=am335x-boneblack-ctag-face.dtb ...
Checking if uname_r is set in /boot/uEnv.txt...
gpio: pin 56 (gpio 56) value is 1
Running uname_boot ...
loading /boot/vmlinuz-4.4.62+ ...
8673712 bytes read in 591 ms (14 MiB/s)
uboot_overlays: dtb=am335x-boneblack-ctag-face.dtb in /boot/uEnv.txt, unable to use [uboot_base_dtb=am335x-boneblack-uboot.dtb] ...
loading /boot/dtbs/4.4.62+/am335x-boneblack-ctag-face.dtb ...
54529 bytes read in 67 ms (793.9 KiB/s)
uboot_overlays: [fdt_buffer=0x60000] ...
uboot_overlays: loading /lib/firmware/M-BB-BBG-00A0.dtbo ...
277 bytes read in 67 ms (3.9 KiB/s)
uboot_overlays: loading /lib/firmware/BB-BONE-eMMC1-01-00A0.dtbo ...
1440 bytes read in 287 ms (4.9 KiB/s)
uboot_overlays: loading /lib/firmware/BB-ADC-00A0.dtbo ...
711 bytes read in 166 ms (3.9 KiB/s)
uboot_overlays: loading /lib/firmware/AM335X-PRU-RPROC-4-14-TI-00A0.dtbo ...
3513 bytes read in 229 ms (14.6 KiB/s)
uboot_overlays: loading /lib/firmware/univ-bbb-Exx-00A0.dtbo ...
104988 bytes read in 274 ms (374 KiB/s)
loading /boot/initrd.img-4.4.62+ ...
7083121 bytes read in 495 ms (13.6 MiB/s)
debug: [console=ttyO0,115200n8 bone_capemgr.uboot_capemgr_enabled=1 root=/dev/mmcblk0p1 ro rootfstype=ext4 rootwait coherent_pool=1M net.ifnames=0 quiet] ...
debug: [bootz 0x82000000 0x88080000:6c1471 88000000] ...
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Ramdisk to 8f93e000, end 8ffff471 ... OK
   reserving fdt memory region: addr=88000000 size=87000
   Loading Device Tree to 8f8b4000, end 8f93dfff ... OK

Starting kernel ...

[    0.003422] clocksource_probe: no matching clocksources found
[    2.268346] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[    2.538889] omap_voltage_late_init: Voltage driver support not added
[    2.547772] PM: Cannot get wkup_m3_ipc handle
rootfs: recovering journal
rootfs: clean, 129917/217728 files, 812987/869376 blocks
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Started Serial Getty on ttyS0.

Debian GNU/Linux 9 beaglebone ttyS0

BeagleBoard.org Debian Image 2018-10-07

Support/FAQ: http://elinux.org/Beagleboard:BeagleBoneBlack_Debian

default username:password is [debian:temppwd]

beaglebone login: [   48.725537] pinctrl-single 44e10800.pinmux: pin 44e109ac.0 already requested by ocp:P9_25_pinmux; cannot claim for 48038000.mcasp
[   48.737473] pinctrl-single 44e10800.pinmux: pin-107 (48038000.mcasp) status -22
[   48.744893] pinctrl-single 44e10800.pinmux: could not request pin 107 (44e109ac.0) from group mcasp0_pins  on device pinctrl-single
[   48.756814] davinci-mcasp 48038000.mcasp: Error applying setting, reverse things back

Debian GNU/Linux 9 beaglebone ttyS0

BeagleBoard.org Debian Image 2018-10-07

Support/FAQ: http://elinux.org/Beagleboard:BeagleBoneBlack_Debian

default username:password is [debian:temppwd]

beaglebone login: debian
Password:
Last login: Wed Mar  6 00:01:00 UTC 2024 on ttyS0

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.
debian@beaglebone:~$

And this is dmesg,

debian@beaglebone:~$ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.62+ (henrik-ubuntu@Ubuntu-NB) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05) ) #1 SMP Mon Oct 15 13:11:18 CEST 2018
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: TI AM335x BeagleBone Green
[    0.000000] cma: Reserved 48 MiB at 0x9c800000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] On node 0 totalpages: 130560
[    0.000000] free_area_init_node: node 0, pgdat c105f1c0, node_mem_map df961000
[    0.000000]   Normal zone: 1152 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 130560 pages, LIFO batch:31
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (sgx neon )
[    0.000000] PERCPU: Embedded 13 pages/cpu @df8e1000 s24268 r8192 d20788 u53248
[    0.000000] pcpu-alloc: s24268 r8192 d20788 u53248 alloc=13*4096
[    0.000000] pcpu-alloc: [0] 0
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 129408
[    0.000000] Kernel command line: console=ttyO0,115200n8 bone_capemgr.uboot_capemgr_enabled=1 root=/dev/mmcblk0p1 ro rootfstype=ext4 rootwait coherent_pool=1M net.ifnames=0 quiet
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 442068K/522240K available (11380K kernel code, 917K rwdata, 3796K rodata, 740K init, 907K bss, 31020K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
                   lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
                   pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
                   modules : 0xbf000000 - 0xbfe00000   (  14 MB)
                     .text : 0xc0008000 - 0xc0eda340   (15177 kB)
                     .init : 0xc0edb000 - 0xc0f94000   ( 740 kB)
                     .data : 0xc0f94000 - 0xc1079488   ( 918 kB)
                      .bss : 0xc107c000 - 0xc115ed50   ( 908 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000]  RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=1
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[    0.000023] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000053] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000065] OMAP clocksource: timer1 at 24000000 Hz
[    0.003422] clocksource_probe: no matching clocksources found
[    0.003736] Console: colour dummy device 80x30
[    0.003772] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
[    0.003780] This ensures that you still see kernel messages. Please
[    0.003786] update your kernel commandline.
[    0.003810] Calibrating delay loop... 995.32 BogoMIPS (lpj=1990656)
[    0.023330] pid_max: default: 32768 minimum: 301
[    0.023485] Security Framework initialized
[    0.023501] Yama: becoming mindful.
[    0.023538] AppArmor: AppArmor disabled by boot time parameter
[    0.023721] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.023734] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.024605] Initializing cgroup subsys io
[    0.024640] Initializing cgroup subsys memory
[    0.024684] Initializing cgroup subsys devices
[    0.024702] Initializing cgroup subsys freezer
[    0.024719] Initializing cgroup subsys net_cls
[    0.024734] Initializing cgroup subsys perf_event
[    0.024750] Initializing cgroup subsys net_prio
[    0.024773] Initializing cgroup subsys pids
[    0.024830] CPU: Testing write buffer coherency: ok
[    0.024892] ftrace: allocating 34533 entries in 102 pages
[    0.122219] CPU0: thread -1, cpu 0, socket -1, mpidr 0
[    0.122348] Setting up static identity map for 0x80008280 - 0x800082e0
[    0.126508] Brought up 1 CPUs
[    0.126533] SMP: Total of 1 processors activated (995.32 BogoMIPS).
[    0.126542] CPU: All CPU(s) started in SVC mode.
[    0.128285] devtmpfs: initialized
[    0.173757] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.255844] omap_hwmod: debugss: _wait_target_disable failed
[    0.297118] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.297157] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.301258] xor: measuring software checksum speed
[    0.339308]    arm4regs  :  1219.000 MB/sec
[    0.379302]    8regs     :   957.000 MB/sec
[    0.419305]    32regs    :  1053.000 MB/sec
[    0.459301]    neon      :  1743.000 MB/sec
[    0.459309] xor: using function: neon (1743.000 MB/sec)
[    0.459434] pinctrl core: initialized pinctrl subsystem
[    0.462404] NET: Registered protocol family 16
[    0.466072] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.468344] cpuidle: using governor ladder
[    0.468370] cpuidle: using governor menu
[    0.485139] OMAP GPIO hardware version 0.1
[    0.503643] No ATAGs?
[    0.503683] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.505632] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.505656] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.583376] raid6: int32x1  gen()   264 MB/s
[    0.651374] raid6: int32x1  xor()   214 MB/s
[    0.719484] raid6: int32x2  gen()   330 MB/s
[    0.787315] raid6: int32x2  xor()   265 MB/s
[    0.855420] raid6: int32x4  gen()   330 MB/s
[    0.923422] raid6: int32x4  xor()   246 MB/s
[    0.991439] raid6: int32x8  gen()   319 MB/s
[    1.059360] raid6: int32x8  xor()   203 MB/s
[    1.127312] raid6: neonx1   gen()  1451 MB/s
[    1.195314] raid6: neonx1   xor()   847 MB/s
[    1.263321] raid6: neonx2   gen()  1944 MB/s
[    1.331313] raid6: neonx2   xor()  1205 MB/s
[    1.399315] raid6: neonx4   gen()  2025 MB/s
[    1.467305] raid6: neonx4   xor()  1224 MB/s
[    1.535344] raid6: neonx8   gen()  1128 MB/s
[    1.603305] raid6: neonx8   xor()   804 MB/s
[    1.603313] raid6: using algorithm neonx4 gen() 2025 MB/s
[    1.603320] raid6: .... xor() 1224 MB/s, rmw enabled
[    1.603327] raid6: using intx1 recovery algorithm
[    1.614521] edma 49000000.edma: TI EDMA DMA engine driver
[    1.626784] SCSI subsystem initialized
[    1.627236] libata version 3.00 loaded.
[    1.627694] usbcore: registered new interface driver usbfs
[    1.627770] usbcore: registered new interface driver hub
[    1.627899] usbcore: registered new device driver usb
[    1.629302] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[    1.630198] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 100 kHz
[    1.630329] omap_i2c 4819c000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c2_pins, deferring probe
[    1.630523] media: Linux media interface: v0.10
[    1.630590] Linux video capture interface: v2.00
[    1.630708] pps_core: LinuxPPS API ver. 1 registered
[    1.630717] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.630745] PTP clock support registered
[    1.632139] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[    1.633621] NetLabel: Initializing
[    1.633636] NetLabel:  domain hash size = 128
[    1.633642] NetLabel:  protocols = UNLABELED CIPSOv4
[    1.633704] NetLabel:  unlabeled traffic allowed by default
[    1.634117] clocksource: Switched to clocksource timer1
[    1.748971] NET: Registered protocol family 2
[    1.749858] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    1.749910] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    1.749969] TCP: Hash tables configured (established 4096 bind 4096)
[    1.750055] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    1.750078] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    1.750536] NET: Registered protocol family 1
[    1.751150] RPC: Registered named UNIX socket transport module.
[    1.751167] RPC: Registered udp transport module.
[    1.751173] RPC: Registered tcp transport module.
[    1.751180] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.752169] Unpacking initramfs...
[    2.218620] Freeing initrd memory: 6920K (cf93e000 - d0000000)
[    2.219336] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    2.223478] audit: initializing netlink subsys (disabled)
[    2.223587] audit: type=2000 audit(2.156:1): initialized
[    2.233692] zbud: loaded
[    2.234522] VFS: Disk quotas dquot_6.6.0
[    2.234840] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.237537] NFS: Registering the id_resolver key type
[    2.237614] Key type id_resolver registered
[    2.237623] Key type id_legacy registered
[    2.237653] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    2.238034] fuse init (API version 7.23)
[    2.238792] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[    2.247269] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    2.247531] io scheduler noop registered
[    2.247549] io scheduler deadline registered
[    2.247623] io scheduler cfq registered (default)
[    2.250780] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[    2.251851] gpio-of-helper ocp:cape-universal: Allocated GPIO id=0
[    2.252053] gpio-of-helper ocp:cape-universal: Allocated GPIO id=1
[    2.252211] gpio-of-helper ocp:cape-universal: Allocated GPIO id=2
[    2.252360] gpio-of-helper ocp:cape-universal: Allocated GPIO id=3
[    2.252511] gpio-of-helper ocp:cape-universal: Allocated GPIO id=4
[    2.252654] gpio-of-helper ocp:cape-universal: Allocated GPIO id=5
[    2.252795] gpio-of-helper ocp:cape-universal: Allocated GPIO id=6
[    2.252951] gpio-of-helper ocp:cape-universal: Allocated GPIO id=7
[    2.253095] gpio-of-helper ocp:cape-universal: Allocated GPIO id=8
[    2.253245] gpio-of-helper ocp:cape-universal: Allocated GPIO id=9
[    2.253381] gpio-of-helper ocp:cape-universal: Allocated GPIO id=10
[    2.253509] gpio-of-helper ocp:cape-universal: Allocated GPIO id=11
[    2.253677] gpio-of-helper ocp:cape-universal: Allocated GPIO id=12
[    2.253828] gpio-of-helper ocp:cape-universal: Allocated GPIO id=13
[    2.253958] gpio-of-helper ocp:cape-universal: Allocated GPIO id=14
[    2.254536] gpio-of-helper ocp:cape-universal: Allocated GPIO id=15
[    2.254714] gpio-of-helper ocp:cape-universal: Allocated GPIO id=16
[    2.254877] gpio-of-helper ocp:cape-universal: Allocated GPIO id=17
[    2.255019] gpio-of-helper ocp:cape-universal: Allocated GPIO id=18
[    2.255150] gpio-of-helper ocp:cape-universal: Allocated GPIO id=19
[    2.255285] gpio-of-helper ocp:cape-universal: Allocated GPIO id=20
[    2.255424] gpio-of-helper ocp:cape-universal: Allocated GPIO id=21
[    2.255558] gpio-of-helper ocp:cape-universal: Allocated GPIO id=22
[    2.255758] gpio-of-helper ocp:cape-universal: Allocated GPIO id=23
[    2.255900] gpio-of-helper ocp:cape-universal: Allocated GPIO id=24
[    2.256040] gpio-of-helper ocp:cape-universal: Allocated GPIO id=25
[    2.256180] gpio-of-helper ocp:cape-universal: Allocated GPIO id=26
[    2.256314] gpio-of-helper ocp:cape-universal: Allocated GPIO id=27
[    2.256450] gpio-of-helper ocp:cape-universal: Allocated GPIO id=28
[    2.256595] gpio-of-helper ocp:cape-universal: Allocated GPIO id=29
[    2.256732] gpio-of-helper ocp:cape-universal: Allocated GPIO id=30
[    2.256879] gpio-of-helper ocp:cape-universal: Allocated GPIO id=31
[    2.257016] gpio-of-helper ocp:cape-universal: Allocated GPIO id=32
[    2.257156] gpio-of-helper ocp:cape-universal: Allocated GPIO id=33
[    2.257302] gpio-of-helper ocp:cape-universal: Allocated GPIO id=34
[    2.257438] gpio-of-helper ocp:cape-universal: Allocated GPIO id=35
[    2.257574] gpio-of-helper ocp:cape-universal: Allocated GPIO id=36
[    2.257719] gpio-of-helper ocp:cape-universal: Allocated GPIO id=37
[    2.257856] gpio-of-helper ocp:cape-universal: Allocated GPIO id=38
[    2.258000] gpio-of-helper ocp:cape-universal: Allocated GPIO id=39
[    2.258361] gpio-of-helper ocp:cape-universal: Allocated GPIO id=40
[    2.258529] gpio-of-helper ocp:cape-universal: Allocated GPIO id=41
[    2.258682] gpio-of-helper ocp:cape-universal: Allocated GPIO id=42
[    2.258827] gpio-of-helper ocp:cape-universal: Allocated GPIO id=43
[    2.258970] gpio-of-helper ocp:cape-universal: Allocated GPIO id=44
[    2.259121] gpio-of-helper ocp:cape-universal: Allocated GPIO id=45
[    2.259262] gpio-of-helper ocp:cape-universal: Allocated GPIO id=46
[    2.259411] gpio-of-helper ocp:cape-universal: Allocated GPIO id=47
[    2.259554] gpio-of-helper ocp:cape-universal: Allocated GPIO id=48
[    2.259701] gpio-of-helper ocp:cape-universal: Allocated GPIO id=49
[    2.259849] gpio-of-helper ocp:cape-universal: Allocated GPIO id=50
[    2.259990] gpio-of-helper ocp:cape-universal: Allocated GPIO id=51
[    2.260132] gpio-of-helper ocp:cape-universal: Allocated GPIO id=52
[    2.260289] gpio-of-helper ocp:cape-universal: Allocated GPIO id=53
[    2.260430] gpio-of-helper ocp:cape-universal: Allocated GPIO id=54
[    2.260579] gpio-of-helper ocp:cape-universal: Allocated GPIO id=55
[    2.260722] gpio-of-helper ocp:cape-universal: Allocated GPIO id=56
[    2.260733] gpio-of-helper ocp:cape-universal: ready
[    2.268346] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[    2.271094] Serial: 8250/16550 driver, 6 ports, IRQ sharing disabled
[    2.274991] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[    2.288435] console [ttyS0] enabled
[    2.289406] 48022000.serial: ttyS1 at MMIO 0x48022000 (irq = 159, base_baud = 3000000) is a 8250
[    2.290319] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 160, base_baud = 3000000) is a 8250
[    2.291110] 481a8000.serial: ttyS4 at MMIO 0x481a8000 (irq = 161, base_baud = 3000000) is a 8250
[    2.291867] 481aa000.serial: ttyS5 at MMIO 0x481aa000 (irq = 162, base_baud = 3000000) is a 8250
[    2.294583] [drm] Initialized drm 1.1.0 20060810
[    2.398227] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[    2.398255] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[    2.399583] davinci_mdio: dt: updated phy_id[0] from phy_mask[fffffffe]
[    2.409446] libphy: 4a101000.mdio: probed
[    2.409483] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720
[    2.410852] cpsw 4a100000.ethernet: Detected MACID = b4:10:7b:78:a0:18
[    2.411021] cpsw 4a100000.ethernet: cpts: overflow check period 2125
[    2.413784] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.413859] ehci-platform: EHCI generic platform driver
[    2.414643] ehci-omap: OMAP-EHCI Host Controller driver
[    2.415712] usbcore: registered new interface driver usb-storage
[    2.419854] 47401300.usb-phy supply vcc not found, using dummy regulator
[    2.423141] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[    2.423164] musb-hdrc: MHDRC RTL version 2.0
[    2.423173] musb-hdrc: setup fifo_mode 4
[    2.423194] musb-hdrc: 28/31 max ep, 16384/16384 memory
[    2.425837] 47401b00.usb-phy supply vcc not found, using dummy regulator
[    2.428918] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[    2.428941] musb-hdrc: MHDRC RTL version 2.0
[    2.428950] musb-hdrc: setup fifo_mode 4
[    2.428967] musb-hdrc: 28/31 max ep, 16384/16384 memory
[    2.429124] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[    2.429162] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
[    2.429476] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    2.429489] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.429499] usb usb1: Product: MUSB HDRC host driver
[    2.429508] usb usb1: Manufacturer: Linux 4.4.62+ musb-hcd
[    2.429517] usb usb1: SerialNumber: musb-hdrc.1.auto
[    2.430853] hub 1-0:1.0: USB hub found
[    2.430950] hub 1-0:1.0: 1 port detected
[    2.441198] mousedev: PS/2 mouse device common for all mice
[    2.445671] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[    2.447644] i2c /dev entries driver
[    2.450805] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[    2.451487] cpuidle: enable-method property 'ti,am3352' found operations
[    2.453451] omap_hsmmc 48060000.mmc: Got CD GPIO
[    2.517410] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.520995] hidraw: raw HID events driver (C) Jiri Kosina
[    2.521874] usbcore: registered new interface driver usbhid
[    2.521887] usbhid: USB HID core driver
[    2.522362] ashmem: initialized
[    2.524351]  remoteproc0: wkup_m3 is available
[    2.524375]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    2.524384]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    2.524622] mmc0: new high speed SDHC card at address 59b4
[    2.528792] mmcblk0: mmc0:59b4 USDU1 7.31 GiB
[    2.530530]  mmcblk0: p1
[    2.533249] NET: Registered protocol family 10
[    2.538412] mip6: Mobile IPv6
[    2.538444] NET: Registered protocol family 17
[    2.538583] Key type dns_resolver registered
[    2.538598] mpls_gso: MPLS GSO support
[    2.538889] omap_voltage_late_init: Voltage driver support not added
[    2.547772] PM: Cannot get wkup_m3_ipc handle
[    2.552442] ThumbEE CPU extension supported.
[    2.552480] Registering SWP/SWPB emulation handler
[    2.556903] registered taskstats version 1
[    2.557141] zswap: loaded using pool lzo/zbud
[    2.561099] Btrfs loaded
[    2.588606] mmc1: MAN_BKOPS_EN bit is not set
[    2.592537] mmc1: new high speed MMC card at address 0001
[    2.593604] Key type encrypted registered
[    2.597239] mmcblk1: mmc1:0001 M62704 3.56 GiB
[    2.599144] input: tps65217_pwr_but as /devices/platform/ocp/44e0b000.i2c/i2c-0/0-0024/input/input0
[    2.600066] mmcblk1boot0: mmc1:0001 M62704 partition 1 2.00 MiB
[    2.600552] mmcblk1boot1: mmc1:0001 M62704 partition 2 2.00 MiB
[    2.602018]  mmcblk1: p1
[    2.624580] tps65217 0-0024: TPS65217 ID 0xe version 1.2
[    2.625374] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    2.625436] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[    2.628460] at24 2-0054: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    2.629069] at24 2-0055: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    2.629582] at24 2-0056: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    2.630067] at24 2-0057: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    2.630356] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 100 kHz
[    2.631957]  remoteproc0: powering up wkup_m3
[    2.631988]  remoteproc0: Booting fw image am335x-pm-firmware.elf, size 217148
[    2.632251]  remoteproc0: remote processor wkup_m3 is now up
[    2.632274] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
[    2.637941] bone_capemgr bone_capemgr: Baseboard: 'A335BNLT,BBG1,BBG122024092'
[    2.637975] bone_capemgr bone_capemgr: compatible-baseboard=ti,beaglebone-black - #slots=4
[    2.638016] bone_capemgr bone_capemgr: slot #0: auto loading handled by U-Boot
[    2.638039] bone_capemgr bone_capemgr: slot #1: auto loading handled by U-Boot
[    2.638061] bone_capemgr bone_capemgr: slot #2: auto loading handled by U-Boot
[    2.638138] bone_capemgr bone_capemgr: slot #3: auto loading handled by U-Boot
[    2.638734] bone_capemgr bone_capemgr: initialized OK.
[    2.644580] PM: bootloader does not support rtc-only!
[    2.646022] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[    2.646054] of_cfs_init
[    2.646414] of_cfs_init: OK
[    2.652766] PM: Hibernation image not present or could not be loaded.
[    2.654325] Freeing unused kernel memory: 740K (c0edb000 - c0f94000)
[    3.092798] random: systemd-udevd: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.093476] random: systemd-udevd: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.093599] random: systemd-udevd: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.113185] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.113582] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.113766] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.114788] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.115407] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.115935] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[    3.116435] random: udevadm: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
[   19.007838] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[   19.651595] systemd[1]: System time before build time, advancing clock.
[   19.738073] ip_tables: (C) 2000-2006 Netfilter Core Team
[   19.782450] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[   19.783139] systemd[1]: Detected architecture arm.
[   19.784766] systemd[1]: Set hostname to <beaglebone>.
[   20.085117] random: nonblocking pool is initialized
[   20.403178] systemd[1]: Listening on udev Kernel Socket.
[   20.404135] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[   20.405669] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   20.406739] systemd[1]: Listening on Journal Audit Socket.
[   20.407322] systemd[1]: Listening on Journal Socket.
[   20.407519] systemd[1]: Reached target Swap.
[   20.407888] systemd[1]: Listening on udev Control Socket.
[   20.408163] systemd[1]: Listening on fsck to fsckd communication Socket.
[   20.409423] systemd[1]: Created slice User and Session Slice.
[   20.409799] systemd[1]: Listening on Syslog Socket.
[   20.411124] systemd[1]: Created slice System Slice.
[   20.417359] systemd[1]: Starting Set the console keyboard layout...
[   20.439798] systemd[1]: Mounting /sys/kernel/debug...
[   20.441569] systemd[1]: Created slice system-serial\x2dgetty.slice.
[   20.541768] systemd[1]: Starting Load Kernel Modules...
[   20.604581] systemd[1]: Created slice system-getty.slice.
[   20.649863] systemd[1]: Starting Remount Root and Kernel File Systems...
[   20.690881] systemd[1]: Listening on Journal Socket (/dev/log).
[   20.691169] systemd[1]: Reached target Remote File Systems.
[   20.691729] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[   20.692244] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   20.692404] systemd[1]: Reached target Paths.
[   20.692534] systemd[1]: Reached target Encrypted Volumes.
[   20.766656] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[   20.766953] systemd[1]: Reached target Slices.
[   20.816452] eqep 48300180.eqep: ver. 1.0
[   20.841443] eqep 48302180.eqep: ver. 1.0
[   20.846791] eqep 48304180.eqep: ver. 1.0
[   20.858884] systemd[1]: Mounting POSIX Message Queue File System...
[   20.955839] systemd[1]: Starting Journal Service...
[   20.998690] EXT4-fs (mmcblk0p1): re-mounted. Opts: errors=remount-ro
[   21.137972] systemd[1]: Mounted POSIX Message Queue File System.
[   21.170772] systemd[1]: Mounted /sys/kernel/debug.
[   21.173569] systemd[1]: Started Load Kernel Modules.
[   21.196960] systemd[1]: Started Remount Root and Kernel File Systems.
[   21.219553] systemd[1]: Started Create list of required static device nodes for the current kernel.
[   21.290750] systemd[1]: Starting Create Static Device Nodes in /dev...
[   21.361254] systemd[1]: Starting udev Coldplug all Devices...
[   21.404085] systemd[1]: Starting Load/Save Random Seed...
[   21.473704] systemd[1]: Mounting FUSE Control File System...
[   21.616120] systemd[1]: Starting Apply Kernel Variables...
[   21.757806] systemd[1]: Mounting Configuration File System...
[   22.000023] systemd[1]: Mounted FUSE Control File System.
[   22.046475] systemd[1]: Started Create Static Device Nodes in /dev.
[   22.048983] systemd[1]: Started Load/Save Random Seed.
[   22.068565] systemd[1]: Started Apply Kernel Variables.
[   22.069807] systemd[1]: Mounted Configuration File System.
[   22.195106] systemd[1]: Starting udev Kernel Device Manager...
[   22.493191] systemd[1]: Started Journal Service.
[   22.764599] systemd-journald[690]: Received request to flush runtime journal from PID 1
[   28.729222] nf_conntrack version 0.5.0 (7795 buckets, 31180 max)
[   29.393500] net eth0: initializing cpsw version 1.12 (0)
[   29.393529] net eth0: initialized cpsw ale version 1.4
[   29.393539] net eth0: ALE Table size 1024
[   29.503831] net eth0: phy found : id is : 0x7c0f1
[   29.542891] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   30.138660] using random self ethernet address
[   30.138689] using random host ethernet address
[   30.262529] using random self ethernet address
[   30.262558] using random host ethernet address
[   30.647606] Mass Storage Function, version: 2009/09/11
[   30.647641] LUN: removable file: (no medium)
[   31.318621] usb0: HOST MAC b4:10:7b:78:a0:19
[   31.319047] usb0: MAC b4:10:7b:78:a0:1a
[   31.343088] usb1: HOST MAC b4:10:7b:78:a0:1c
[   31.347072] usb1: MAC b4:10:7b:78:a0:1d
[   32.519205] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   32.519302] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   32.688582] 8021q: 802.1Q VLAN Support v1.8
[   32.688674] 8021q: adding VLAN 0 to HW filter on device eth0
[   32.883435] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[   33.175259] IPv6: ADDRCONF(NETDEV_UP): usb1: link is not ready
[   48.725537] pinctrl-single 44e10800.pinmux: pin 44e109ac.0 already requested by ocp:P9_25_pinmux; cannot claim for 48038000.mcasp
[   48.737473] pinctrl-single 44e10800.pinmux: pin-107 (48038000.mcasp) status -22
[   48.744893] pinctrl-single 44e10800.pinmux: could not request pin 107 (44e109ac.0) from group mcasp0_pins  on device pinctrl-single
[   48.756814] davinci-mcasp 48038000.mcasp: Error applying setting, reverse things back
[   65.769677] CAN device driver interface
[   65.855069] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=169)
[   65.874425] c_can_platform 481d0000.can: c_can_platform device registered (regs=fa1d0000, irq=170)
[   66.556861] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[   67.541012] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[   67.567421] omap-sham 53100000.sham: hw accel on OMAP rev 4.3

Furthermore, we try another bootup test - Run aplay before load driver, and the output is,
aplay: device_list:270: no soundcards found...

And we also try another bootup test - Fix apt sources.list before load driver, but it does not help. The apt fix goes like this, run the followings as root,
echo "deb http://archive.debian.org/debian stretch main contrib non-free" > /etc/apt/sources.list
apt-get update

Finally, after prompting GPT-4 with the serial console log and dmesg data, I figure out the most suspicious problem is,

Peripheral Configuration Issues: Similar to the bootloader output, there are errors related to the configuration of peripherals, such as the inability to claim pins for the "davinci-mcasp" audio interface due to conflicts, indicating issues in pin multiplexing or device overlay configurations.

    moseswong001 And that BBG audio software runs on top the same v0.1.2 downloaded from https://github.com/ctag-fh-kiel/bela-ctag/releases. We md5 checked these two images and they are identical, therefore, the problem look strange to us.

    those images haven't changed in 6 years, so it's unlikely a fresh new one shows such errors unless something changed in the BBG design since then . Is this the stock image you are running or have you made any modifications to it?

      I am running the test using the v0.1.2 image downloaded from the link https://github.com/ctag-fh-kiel/bela-ctag/releases. If this is what 'stock image' you are referring to, yes, I am using stock image. And, no, I made no change to it, just download, write to a microSD and boot up the BBG + CTAG-Face with it.

      For the BBG, it was purchased in Germany at digikey.de in around April 2022. I am a software engineer, no hardware background, so probably you have a better idea if it is due to BBG changes.

      I do not have to make the CTAG-Face work on this particular BBG, the goal is CTAG-Face with any Beaglebone Green platforms. So if I can purchase a new BBG to solve the problem I can do that.

      Thanks a lot!

      According to this there is a boot button at the back. Try booting while you press that and see if things change

        giuliomoro Try booting while you press that and see if things change

        Is this what made it work?

          Yes, it did make it work.

          I always assume BBG/BBB default boot sequence allows inserted microSD to go before onboard eMMC. I reviewed older logs and remembered many instances by just inserting a microSD into a brand new BBG without doing anything else and it just boots up properly.

          I do not know why a problem happened to me this time. But thank you for pointing out the Boot button of BBG, after pressing and holding this button during the boot period the system boots up with the microSD, which is v0.1.2 of CTAG Debian, and works properly. This includes the mount command showing root path is already mounted on /dev/mmcblk0 (i.e. microSD) and sudo run the load driver script ./load-ctag-xxx.sh works properly. So, the problem is solved. Thanks once again, giuliomoro, it is a big help!

          Just for the record, if anyone else wonders what is happening here - BBG/BBB factory boot loader simply does not boot up with a microSD inserted, you need to press this Boot button to tell the system to boot up from the microSD, that's the design idea.

          As my preference, I follow the "Always boot from microSD" section of this guide to delete the BBG/BBB onboard boot loader from eMMC and this helps me skip the procedure manually pressing this Boot button which is challenging when CTAG is attached on GPIO.

          Boots up a new BBG/BBB without microSD nor CTAG inserted, and type the following command to remove the boot loader, this system's onboard Operating System is now void and the only way to boot it up is microSD,

          sudo dd if=/dev/zero of=/dev/mmcblk1 bs=1024 count=1024