SDMA Errors in console

I have a custom Linux build on an Apalis iMX6Q IT module

root@acu3:~# uname -a
Linux acu3 6.0.5-rt14-6.1.0-devel+git.040e3123e9d9 #1 SMP PREEMPT_RT Thu Oct 27 07:48:28 UTC 2022 armv7l armv7l armv7l GNU/Linux

I was building my OS with the dbg-pkgs option enabled in the local.conf file. I have removed that option and rebuilt and now the console is being flooded with the below errors.

[ 1514.978943] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 1514.980277] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 1514.981728] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 1515.978103] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 1515.979987] imx-sdma 20ec000.sdma: restart cyclic channel 1

I don’t even know where to begin trying to figure out what the issue may be. Googling has not been helpful, any assistance would be greatly appreciated

Check the system logs to see if there are any other error messages that might be related to the issue. Use the “dmesg” command to view the kernel messages.

@alex.tx

The only message related to sdma is

root@acu3:~# dmesg | grep sdma
[ 21.186163] imx-sdma 20ec000.sdma: loaded firmware 3.5

Could you attach the whole dmesg log?

@alex.tx

Sure. A couple notes about this custom Linux distro that may be relevant

  1. This is supposed to be a console only distro. No graphical output at all. Not sure I disabled everything in that regard

  2. This distro does not utilize audio, bluetooth, WiFi, USB, PCIe, or camera. Again, not sure everything got disabled properly but fairly certain

root@acu3:~# dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 6.0.5-rt14-6.1.0-devel+git.040e3123e9d9 (oe-user@oe-host) (arm-tdx-linux-gnueabi-gcc (GCC) 11.3.0, GNU ld (GNU Binutils) 2.38.20220708) #1 SMP PREEMPT_RT Thu Oct 27 07:48:28 UTC 2022
[ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] OF: fdt: Machine model: Toradex Apalis iMX6Q/D Module on ACU-3 Carrier Board
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] cma: Reserved 256 MiB at 0x80000000
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000010000000-0x0000000035ffffff]
[ 0.000000] HighMem [mem 0x0000000036000000-0x000000008fffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000010000000-0x000000008fffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000010000000-0x000000008fffffff]
[ 0.000000] percpu: Embedded 17 pages/cpu s39892 r8192 d21548 u69632
[ 0.000000] pcpu-alloc: s39892 r8192 d21548 u69632 alloc=17*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 523072
[ 0.000000] Kernel command line: enable_wait_mode=off vmalloc=400M root=PARTUUID=5f29b799-02 ro rootwait fec_mac=00:14:2d:a4:5d:78 consoleblank=0 no_console_suspend=1 console=tty1 console=ttymxc0,115200n8 mxc_hdmi.only_cea=1 fbmem=32M
[ 0.000000] Unknown kernel command line parameters “enable_wait_mode=off fec_mac=00:14:2d:a4:5d:78 fbmem=32M”, will be passed to user space.
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] Memory: 1790316K/2097152K available (12288K kernel code, 1839K rwdata, 4140K rodata, 1024K init, 6687K bss, 44692K reserved, 262144K cma-reserved, 1212416K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] trace event string verifier disabled
[ 0.000000] Running RCU self tests
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU event tracing is enabled.
[ 0.000000] rcu: RCU lockdep checking is enabled.
[ 0.000000] rcu: RCU priority boosting: priority 1 delay 500 ms.
[ 0.000000] rcu: RCU_SOFTIRQ processing moved to rcuc kthreads.
[ 0.000000] No expedited grace period (rcu_normal_after_boot).
[ 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 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] L2C-310 errata 752271 769419 enabled
[ 0.000000] L2C-310 enabling early BRESP for Cortex-A9
[ 0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[ 0.000000] L2C-310 ID prefetch enabled, offset 16 lines
[ 0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[ 0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB
[ 0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x76470001
[ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[ 0.000000] Switching to timer-based delay loop, resolution 333ns
[ 0.000001] sched_clock: 32 bits at 3000kHz, resolution 333ns, wraps every 715827882841ns
[ 0.000029] clocksource: mxc_timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 637086815595 ns
[ 0.003068] Console: colour dummy device 80x30
[ 0.008692] printk: console [tty1] enabled
[ 0.008703] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.008708] … MAX_LOCKDEP_SUBCLASSES: 8
[ 0.008713] … MAX_LOCK_DEPTH: 48
[ 0.008717] … MAX_LOCKDEP_KEYS: 8192
[ 0.008723] … CLASSHASH_SIZE: 4096
[ 0.008729] … MAX_LOCKDEP_ENTRIES: 32768
[ 0.008734] … MAX_LOCKDEP_CHAINS: 65536
[ 0.008740] … CHAINHASH_SIZE: 32768
[ 0.008745] memory used by lock dependency info: 4061 kB
[ 0.008751] memory used for stack traces: 2112 kB
[ 0.008757] per task-struct memory footprint: 1536 bytes
[ 0.008930] Calibrating delay loop (skipped), value calculated using timer frequency… 6.00 BogoMIPS (lpj=30000)
[ 0.008948] pid_max: default: 32768 minimum: 301
[ 0.011250] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.011290] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.020596] CPU: Testing write buffer coherency: ok
[ 0.020945] CPU0: Spectre v2: using BPIALL workaround
[ 0.023989] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.035399] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.035497] cblist_init_generic: Setting shift to 2 and lim to 1.
[ 0.036938] cblist_init_generic: Setting shift to 2 and lim to 1.
[ 0.038195] Running RCU-tasks wait API self tests
[ 0.140474] Setting up static identity map for 0x10100000 - 0x10100078
[ 0.144600] rcu: Hierarchical SRCU implementation.
[ 0.144607] rcu: Max phase no-delay instances is 1000.
[ 0.145431] printk: console [tty1] printing thread started
[ 0.157082] smp: Bringing up secondary CPUs …
[ 0.168665] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.168686] CPU1: Spectre v2: using BPIALL workaround
[ 0.181310] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.181330] CPU2: Spectre v2: using BPIALL workaround
[ 0.182456] Callback from call_rcu_tasks_trace() invoked.
[ 0.192066] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.192083] CPU3: Spectre v2: using BPIALL workaround
[ 0.193035] smp: Brought up 1 node, 4 CPUs
[ 0.193050] SMP: Total of 4 processors activated (24.00 BogoMIPS).
[ 0.193801] CPU: All CPU(s) started in SVC mode.
[ 0.201768] devtmpfs: initialized
[ 0.561336] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[ 0.567202] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.567287] futex hash table entries: 1024 (order: 5, 131072 bytes, linear)
[ 0.580069] Callback from call_rcu_tasks() invoked.
[ 0.586188] pinctrl core: initialized pinctrl subsystem
[ 0.608071] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.721012] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.751874] thermal_sys: Registered thermal governor ‘step_wise’
[ 0.752967] cpuidle: using governor menu
[ 0.753100] CPU identified as i.MX6Q, silicon rev 1.6
[ 1.015127] platform 2800000.ipu: Fixing up cyclic dependency with 20e0000.iomuxc-gpr:ipu2_csi1_mux
[ 1.071917] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
[ 1.071936] hw-breakpoint: maximum watchpoint size is 4 bytes.
[ 1.091347] imx6q-pinctrl 20e0000.pinctrl: initialized IMX pinctrl driver
[ 1.338524] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[ 1.655988] SCSI subsystem initialized
[ 1.656862] libata version 3.00 loaded.
[ 1.662370] usbcore: registered new interface driver usbfs
[ 1.663496] usbcore: registered new interface driver hub
[ 1.664198] usbcore: registered new device driver usb
[ 1.665934] usb_phy_generic usbphynop1: supply vcc not found, using dummy regulator
[ 1.669706] usb_phy_generic usbphynop1: dummy supplies not allowed for exclusive requests
[ 1.671543] usb_phy_generic usbphynop2: supply vcc not found, using dummy regulator
[ 1.674620] usb_phy_generic usbphynop2: dummy supplies not allowed for exclusive requests
[ 1.731234] stmpe-i2c 2-0041: stmpe811 detected, chip id: 0x811
[ 1.888378] i2c i2c-2: IMX I2C adapter registered
[ 1.896942] mc: Linux media interface: v0.10
[ 1.898154] videodev: Linux video capture interface: v2.00
[ 1.900928] pps_core: LinuxPPS API ver. 1 registered
[ 1.900939] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it
[ 1.901184] PTP clock support registered
[ 1.921259] Bluetooth: Core ver 2.22
[ 1.921737] NET: Registered PF_BLUETOOTH protocol family
[ 1.921746] Bluetooth: HCI device and connection manager initialized
[ 1.922059] Bluetooth: HCI socket layer initialized
[ 1.922159] Bluetooth: L2CAP socket layer initialized
[ 1.922674] Bluetooth: SCO socket layer initialized
[ 1.932667] clocksource: Switched to clocksource mxc_timer1
[ 1.942116] VFS: Disk quotas dquot_6.6.0
[ 1.943099] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 2.096655] NET: Registered PF_INET protocol family
[ 2.099617] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 2.107512] tcp_listen_portaddr_hash hash table entries: 512 (order: 3, 36864 bytes, linear)
[ 2.108366] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 2.108516] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 2.109990] TCP bind hash table entries: 8192 (order: 7, 589824 bytes, linear)
[ 2.112763] TCP: Hash tables configured (established 8192 bind 8192)
[ 2.114411] UDP hash table entries: 512 (order: 4, 81920 bytes, linear)
[ 2.115086] UDP-Lite hash table entries: 512 (order: 4, 81920 bytes, linear)
[ 2.117220] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 2.124965] RPC: Registered named UNIX socket transport module.
[ 2.124999] RPC: Registered udp transport module.
[ 2.125020] RPC: Registered tcp transport module.
[ 2.125040] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 2.125105] PCI: CLS 0 bytes, default 64
[ 2.137288] armv7-pmu pmu: hw perfevents: no interrupt-affinity property, guessing.
[ 2.139125] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available
[ 2.171232] Initialise system trusted keyrings
[ 2.173946] workingset: timestamp_bits=30 max_order=19 bucket_order=0
[ 2.359214] NFS: Registering the id_resolver key type
[ 2.359646] Key type id_resolver registered
[ 2.359679] Key type id_legacy registered
[ 2.361726] nfs4filelayout_init: NFSv4 File Layout Driver Registering…
[ 2.361967] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering…
[ 2.458781] Key type asymmetric registered
[ 2.459064] Asymmetric key parser ‘x509’ registered
[ 2.463055] bounce: pool size: 64 pages
[ 2.465664] io scheduler mq-deadline registered
[ 2.465692] io scheduler kyber registered
[ 2.538214] mxs-dma 110000.dma-apbh: initialized
[ 2.581610] pfuze100-regulator 2-0008: Full layer: 2, Metal layer: 1
[ 2.583618] pfuze100-regulator 2-0008: FAB: 0, FIN: 0
[ 2.583635] pfuze100-regulator 2-0008: pfuze100 found.
[ 2.694616] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 268, base_baud = 5000000) is a IMX
[ 2.696665] printk: console [ttymxc0] printing thread started
[ 2.698430] printk: console [ttymxc0] enabled
[ 2.729236] 21e8000.serial: ttymxc1 at MMIO 0x21e8000 (irq = 269, base_baud = 5000000) is a IMX
[ 2.742714] 21ec000.serial: ttymxc2 at MMIO 0x21ec000 (irq = 270, base_baud = 5000000) is a IMX
[ 2.756768] 21f0000.serial: ttymxc3 at MMIO 0x21f0000 (irq = 271, base_baud = 5000000) is a IMX
[ 2.769927] 21f4000.serial: ttymxc4 at MMIO 0x21f4000 (irq = 272, base_baud = 5000000) is a IMX
[ 2.870994] etnaviv etnaviv: bound 130000.gpu (ops gpu_ops)
[ 2.875058] etnaviv etnaviv: bound 134000.gpu (ops gpu_ops)
[ 2.878626] etnaviv etnaviv: bound 2204000.gpu (ops gpu_ops)
[ 2.878675] etnaviv-gpu 130000.gpu: model: GC2000, revision: 5108
[ 2.884956] etnaviv-gpu 134000.gpu: model: GC320, revision: 5007
[ 2.885377] etnaviv-gpu 2204000.gpu: model: GC355, revision: 1215
[ 2.885396] etnaviv-gpu 2204000.gpu: Ignoring GPU with VG and FE2.0
[ 2.897469] [drm] Initialized etnaviv 1.3.0 20151214 for etnaviv on minor 0
[ 2.921626] imx-ipuv3 2400000.ipu: IPUv3H probed
[ 2.939178] Stack Depot allocating hash table of 65536 entries with kvcalloc
[ 2.955503] imx-drm display-subsystem: bound imx-ipuv3-crtc.2 (ops ipu_crtc_ops)
[ 2.959239] imx-drm display-subsystem: bound imx-ipuv3-crtc.3 (ops ipu_crtc_ops)
[ 2.964307] imx-drm display-subsystem: bound imx-ipuv3-crtc.6 (ops ipu_crtc_ops)
[ 2.968233] imx-drm display-subsystem: bound imx-ipuv3-crtc.7 (ops ipu_crtc_ops)
[ 2.976132] [drm] Initialized imx-drm 1.0.0 20120507 for display-subsystem on minor 1
[ 2.977176] imx-ipuv3 2800000.ipu: IPUv3H probed
[ 3.168126] brd: module loaded
[ 3.327969] loop: module loaded
[ 3.335821] ahci-imx 2200000.sata: fsl,transmit-level-mV not specified, using 00000024
[ 3.335845] ahci-imx 2200000.sata: fsl,transmit-boost-mdB not specified, using 00000480
[ 3.335860] ahci-imx 2200000.sata: fsl,transmit-atten-16ths not specified, using 00002000
[ 3.335876] ahci-imx 2200000.sata: fsl,receive-eq-mdB not specified, using 05000000
[ 3.336859] ahci-imx 2200000.sata: supply ahci not found, using dummy regulator
[ 3.340212] ahci-imx 2200000.sata: supply phy not found, using dummy regulator
[ 3.341891] ahci-imx 2200000.sata: supply target not found, using dummy regulator
[ 3.348859] ahci-imx 2200000.sata: SSS flag set, parallel bus scan disabled
[ 3.348892] ahci-imx 2200000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[ 3.348912] ahci-imx 2200000.sata: flags: ncq sntf stag pm led clo only pmp pio slum part ccc apst
[ 3.372827] scsi host0: ahci-imx
[ 3.381633] ata1: SATA max UDMA/133 mmio [mem 0x02200000-0x02203fff] port 0x100 irq 284
[ 3.421263] CAN device driver interface
[ 3.487781] pps pps0: new PPS source ptp0
[ 3.546016] fec 2188000.ethernet eth0: registered PHC device 0
[ 3.555598] ehci_hcd: USB 2.0 ‘Enhanced’ Host Controller (EHCI) Driver
[ 3.555612] ehci-pci: EHCI PCI platform driver
[ 3.557365] usbcore: registered new interface driver usb-storage
[ 3.608932] snvs_rtc 20cc000.snvs:snvs-rtc-lp: registered as rtc1
[ 3.610651] i2c_dev: i2c /dev entries driver
[ 3.621582] pps_ldisc: PPS line discipline registered
[ 3.627672] pps pps1: new PPS source pps.-1
[ 3.629649] pps pps1: Registered IRQ 66 as PPS source
[ 3.659793] Bluetooth: HCI UART driver ver 2.3
[ 3.659813] Bluetooth: HCI UART protocol H4 registered
[ 3.660293] Bluetooth: HCI UART protocol LL registered
[ 3.676371] sdhci: Secure Digital Host Controller Interface driver
[ 3.676382] sdhci: Copyright(c) Pierre Ossman
[ 3.676388] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.690700] sdhci-esdhc-imx 2190000.mmc: Got CD GPIO
[ 3.692449] caam 2100000.crypto: Entropy delay = 3200
[ 3.723848] ata1: SATA link down (SStatus 0 SControl 300)
[ 3.724587] ahci-imx 2200000.sata: no device found, disabling link.
[ 3.724599] ahci-imx 2200000.sata: pass ahci_imx…hotplug=1 to enable hotplug
[ 3.753681] caam 2100000.crypto: Instantiated RNG4 SH0
[ 3.770038] mmc0: SDHCI controller on 2190000.mmc [2190000.mmc] using ADMA
[ 3.775103] mmc2: SDHCI controller on 2198000.mmc [2198000.mmc] using ADMA
[ 3.814444] caam 2100000.crypto: Instantiated RNG4 SH1
[ 3.814534] caam 2100000.crypto: device ID = 0x0a16010000000000 (Era 4)
[ 3.814548] caam 2100000.crypto: job rings = 2, qi = 0
[ 3.824327] mmc0: new high speed SD card at address 0007
[ 3.840598] mmcblk0: mmc0:0007 DDINC 1.85 GiB
[ 3.844037] mmc2: new DDR MMC card at address 0001
[ 3.854233] mmcblk2: mmc2:0001 Q2J54A 3.59 GiB
[ 3.858380] caam algorithms registered in /proc/crypto
[ 3.859503] caam 2100000.crypto: registering rng-caam
[ 3.879060] caam 2100000.crypto: rng crypto API alg registered prng-caam
[ 3.908802] usbcore: registered new interface driver usbhid
[ 3.908815] usbhid: USB HID core driver
[ 3.925200] imx-ipuv3-csi imx-ipuv3-csi.0: Registered ipu1_csi0 capture as /dev/video0
[ 3.930419] imx-ipuv3 2400000.ipu: Registered ipu1_ic_prpenc capture as /dev/video1
[ 3.936046] imx-ipuv3 2400000.ipu: Registered ipu1_ic_prpvf capture as /dev/video2
[ 3.945401] imx-ipuv3-csi imx-ipuv3-csi.1: Registered ipu1_csi1 capture as /dev/video3
[ 3.946112] mmcblk0: p1
[ 3.952289] mmcblk2: p1 p2
[ 3.952703] imx-ipuv3-csi imx-ipuv3-csi.4: Registered ipu2_csi0 capture as /dev/video4
[ 3.964240] imx-ipuv3 2800000.ipu: Registered ipu2_ic_prpenc capture as /dev/video5
[ 3.968422] imx-ipuv3 2800000.ipu: Registered ipu2_ic_prpvf capture as /dev/video6
[ 3.974827] imx-ipuv3-csi imx-ipuv3-csi.5: Registered ipu2_csi1 capture as /dev/video7
[ 3.977442] mmcblk2boot0: mmc2:0001 Q2J54A 16.0 MiB
[ 3.992500] stmpe-adc stmpe-adc: DMA mask not set
[ 4.016562] mmcblk2boot1: mmc2:0001 Q2J54A 16.0 MiB
[ 4.055627] mmcblk2rpmb: mmc2:0001 Q2J54A 512 KiB, chardev (243:0)
[ 4.058224] random: crng init done
[ 4.063763] NET: Registered PF_INET6 protocol family
[ 4.082997] Segment Routing with IPv6
[ 4.083271] In-situ OAM (IOAM) with IPv6
[ 4.083829] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 4.095843] NET: Registered PF_PACKET protocol family
[ 4.095998] can: controller area network core
[ 4.096873] NET: Registered PF_CAN protocol family
[ 4.096910] can: raw protocol
[ 4.097290] can: broadcast manager protocol
[ 4.097442] can: netlink gateway - max_hops=1
[ 4.100364] Key type dns_resolver registered
[ 4.155648] Registering SWP/SWPB emulation handler
[ 4.160241] Loading compiled-in X.509 certificates
[ 4.968782] video-mux 20e0000.iomuxc-gpr:ipu2_csi1_mux: Consider updating driver video-mux to match on endpoints
[ 5.018700] imx-media: Registered ipu_ic_pp csc/scaler as /dev/video8
[ 5.041489] imx_thermal 20c8000.anatop:tempmon: Industrial CPU temperature grade - max:105C critical:105C passive:95C
[ 5.066344] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 5.098961] input: gpio-pbs as /devices/platform/gpio-pbs/input/input1
[ 5.184481] EXT4-fs (mmcblk2p2): mounted filesystem with ordered data mode. Quota mode: none.
[ 5.185414] VFS: Mounted root (ext4 filesystem) readonly on device 179:10.
[ 5.191018] devtmpfs: mounted
[ 5.198106] Freeing unused kernel image (initmem) memory: 1024K
[ 5.204829] Run /sbin/init as init process
[ 5.204840] with arguments:
[ 5.204845] /sbin/init
[ 5.204850] with environment:
[ 5.204854] HOME=/
[ 5.204859] TERM=linux
[ 5.204863] enable_wait_mode=off
[ 5.204867] fec_mac=00:14:2d:a4:5d:78
[ 5.204872] fbmem=32M
[ 5.657590] systemd[1]: System time before build time, advancing clock.
[ 5.892155] systemd[1]: systemd 250.5+ running in system mode (+PAM -AUDIT -SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN -IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=hybrid)
[ 5.898931] systemd[1]: Detected architecture arm.
[ 5.974726] systemd[1]: Hostname set to .
[ 5.991715] systemd[1]: Initializing machine ID from random generator.
[ 5.997555] systemd[1]: Installed transient /etc/machine-id file.
[ 8.209986] systemd[1]: Queued start job for default target Multi-User System.
[ 8.599797] systemd[1]: Created slice Slice /system/getty.
[ 8.663264] systemd[1]: Created slice Slice /system/modprobe.
[ 8.719691] systemd[1]: Created slice Slice /system/serial-getty.
[ 8.788854] systemd[1]: Created slice User and Session Slice.
[ 8.845748] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 8.901992] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 8.945492] systemd[1]: Reached target Path Units.
[ 8.984714] systemd[1]: Reached target Remote File Systems.
[ 9.034497] systemd[1]: Reached target Slice Units.
[ 9.064774] systemd[1]: Reached target Swaps.
[ 9.395892] systemd[1]: Listening on RPCbind Server Activation Socket.
[ 9.445067] systemd[1]: Reached target RPC Port Mapper.
[ 9.471891] systemd[1]: Listening on Syslog Socket.
[ 9.521938] systemd[1]: Listening on initctl Compatibility Named Pipe.
[ 9.623376] systemd[1]: Journal Audit Socket was skipped because of a failed condition check (ConditionSecurity=audit).
[ 9.629522] systemd[1]: Listening on Journal Socket (/dev/log).
[ 9.710561] systemd[1]: Listening on Journal Socket.
[ 9.754163] systemd[1]: Listening on Network Service Netlink Socket.
[ 9.809572] systemd[1]: Listening on udev Control Socket.
[ 9.875162] systemd[1]: Listening on udev Kernel Socket.
[ 9.898172] systemd[1]: Listening on User Database Manager Socket.
[ 9.952544] systemd[1]: Huge Pages File System was skipped because of a failed condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[ 9.955928] systemd[1]: POSIX Message Queue File System was skipped because of a failed condition check (ConditionPathExists=/proc/sys/fs/mqueue).
[ 10.080058] systemd[1]: Mounting Kernel Debug File System…
[ 10.163195] systemd[1]: Mounting Kernel Trace File System…
[ 10.351913] systemd[1]: Mounting Temporary Directory /tmp…
[ 10.443831] systemd[1]: Starting Create List of Static Device Nodes…
[ 10.537757] systemd[1]: Starting Load Kernel Module configfs…
[ 10.614858] systemd[1]: Starting Load Kernel Module drm…
[ 10.686230] systemd[1]: Starting Load Kernel Module fuse…
[ 10.771815] systemd[1]: Starting RPC Bind…
[ 10.779659] fuse: init (API version 7.36)
[ 10.876323] systemd[1]: Starting File System Check on Root Device…
[ 11.048256] systemd[1]: Starting Journal Service…
[ 11.064642] systemd[1]: Load Kernel Modules was skipped because all trigger condition checks failed.
[ 11.105248] systemd[1]: Starting Generate network units from Kernel command line…
[ 11.149699] systemd[1]: Starting Apply Kernel Variables…
[ 11.195928] systemd[1]: Starting Coldplug All udev Devices…
[ 11.495753] systemd[1]: Started RPC Bind.
[ 11.502245] systemd[1]: Mounted Kernel Debug File System.
[ 11.514874] systemd[1]: Mounted Kernel Trace File System.
[ 11.577006] systemd[1]: Mounted Temporary Directory /tmp.
[ 11.624900] systemd[1]: Finished Create List of Static Device Nodes.
[ 11.683975] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[ 11.697554] systemd[1]: Finished Load Kernel Module configfs.
[ 11.762406] systemd[1]: modprobe@drm.service: Deactivated successfully.
[ 11.773132] systemd[1]: Finished Load Kernel Module drm.
[ 11.809082] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[ 11.821036] systemd[1]: Finished Load Kernel Module fuse.
[ 11.876957] systemd[1]: Finished File System Check on Root Device.
[ 11.930517] systemd[1]: Finished Generate network units from Kernel command line.
[ 11.988429] systemd[1]: Finished Apply Kernel Variables.
[ 12.160824] systemd[1]: Mounting FUSE Control File System…
[ 12.246876] systemd[1]: Mounting Kernel Configuration File System…
[ 12.304448] systemd[1]: Starting Remount Root and Kernel File Systems…
[ 12.358025] systemd[1]: Started Journal Service.
[ 12.681596] EXT4-fs (mmcblk2p2): re-mounted. Quota mode: none.
[ 13.044159] systemd-journald[251]: Received client request to flush runtime journal.
[ 20.434866] coda 2040000.vpu: Firmware code revision: 46076
[ 20.434965] coda 2040000.vpu: Initialized CODA960.
[ 20.434975] coda 2040000.vpu: Firmware version: 3.1.1
[ 20.550337] coda 2040000.vpu: coda-jpeg-encoder registered as video9
[ 20.560174] coda 2040000.vpu: coda-jpeg-decoder registered as video10
[ 20.567439] coda 2040000.vpu: coda-video-encoder registered as video11
[ 20.588598] coda 2040000.vpu: coda-video-decoder registered as video12
[ 21.186163] imx-sdma 20ec000.sdma: loaded firmware 3.5
[ 35.179989] 8021q: 802.1Q VLAN Support v1.8
[ 35.766917] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 35.881891] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 35.887945] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ 35.888116] cfg80211: failed to load regulatory.db
[ 37.392783] Micrel KSZ9031 Gigabit PHY 2188000.ethernet-1:07: attached PHY driver (mii_bus:phy_addr=2188000.ethernet-1:07, irq=58)
[ 40.521535] flexcan 2090000.can can0: bit-timing not yet defined
[ 40.541543] flexcan 2094000.can can1: bit-timing not yet defined
[ 40.695818] fec 2188000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 40.696877] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 42.876523] pps pps2: new PPS source IMX-uart4
[ 42.877136] pps pps2: source “/dev/ttymxc4” added
[ 81.519085] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 86.513409] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 88.513950] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 88.515439] imx-sdma 20ec000.sdma: restart cyclic channel 1

[ 143.519756] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 143.521102] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 143.522462] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 144.516258] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 144.549730] pps pps2: removed
[ 145.516073] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 145.517674] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 145.519222] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 145.520929] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 145.522626] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 145.523878] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 148.515076] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 148.517084] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 148.518546] imx-sdma 20ec000.sdma: restart cyclic channel 1

[ 195.526633] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 195.528171] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 195.529693] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 195.530835] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 195.611578] systemd-journald[251]: Data hash table of /run/log/journal/41c4f92e33874454972f27847b2ebcd8/system.journal has a fill level at 75.0 (2731 of 3640 items, 2097152 file size, 767 bytes per hash table item), suggesting rotation.
[ 195.611667] systemd-journald[251]: /run/log/journal/41c4f92e33874454972f27847b2ebcd8/system.journal: Journal header limits reached or header out-of-date, rotating.
[ 196.522474] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 196.523854] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 196.525255] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 196.526870] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 196.528010] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 197.522813] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 197.524196] imx-sdma 20ec000.sdma: restart cyclic channel 1

[ 2992.306090] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2992.307259] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2992.308720] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2992.310001] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2992.383858] systemd-journald[251]: Data hash table of /run/log/journal/41c4f92e33874454972f27847b2ebcd8/system.journal has a fill level at 75.0 (2731 of 3640 items, 2097152 file size, 767 bytes per hash table item), suggesting rotation.
[ 2992.383945] systemd-journald[251]: /run/log/journal/41c4f92e33874454972f27847b2ebcd8/system.journal: Journal header limits reached or header out-of-date, rotating.
[ 2994.314127] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2994.316109] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2994.317274] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2995.305162] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2995.306493] imx-sdma 20ec000.sdma: restart cyclic channel 1
[ 2995.307780] imx-sdma 20ec000.sdma: restart cyclic channel 1

Looks like a kernel debug is still enabled and some driver is spewing debug messages about restarting DMA channel. Most likely it’s a UART driver.

@alex.tx

Ok, so what can be done about it? What is interesting (if that is a kernel debug message) is that this didn’t happen when the OS had the ‘dbg-pkgs’ option enabled. Do I need to try and rebuild again? Or is there another option that I am missing?

Please refer this thread - debugging - enable linux kernel driver dev_dbg debug messages - Stack Overflow

@alex.tx

So it seems that maybe some fragments were left behind when I removed the dbg-pkgs option. I updated to the latest HEAD revision of kirkstone and then did a full clean build and the problem went away.

Thanks