Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device

Alsa-Devel Archive on lore.kernel.org
 help / color / mirror / Atom feed

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
       [not found] <CADs9LoPZH_D+eJ9qjTxSLE5jGyhKsjMN7g2NighZ16biVxsyKw@mail.gmail.com>
@ 2022-06-27 15:12 ` Pierre-Louis Bossart
  2022-06-27 16:52   ` Mark Brown
       [not found]   ` <CADs9LoN-L0X1Dr1sP2K7xrcWm7dpHW6MhF47c2eBB0moLNnPRQ@mail.gmail.com>
  0 siblings, 2 replies; 11+ messages in thread
From: Pierre-Louis Bossart @ 2022-06-27 15:12 UTC (permalink / raw)
  To: Alex Natalsson, Kai Vehmanen, Bard Liao, Ranjani Sridharan,
	Takashi Iwai, Mark Brown, linux-sound,
	ALSA Development Mailing List

[Adding alsa-devel]

On 6/26/22 04:29, Alex Natalsson wrote:
> Hello friends.
> I have Khadas VIM3 SBC with Amlogic A311D SOC device.
> After update kernel my media software (alsamixer, mplayer, ffplay and
> etc.) not responding after launch and not terminating throught kill
> -9.
> 
> The log after alsamixer launch:
> [  103.786358] Internal error: Oops: 96000004 [#1] SMP
> [  103.786509] Modules linked in: snd_seq_dummy snd_hrtimer snd_seq
> snd_seq_device bnep nls_utf8 mt7601u mac80211 hci_uart libarc4 joydev
> brcmfmac btbcm panfrost bluetooth meson_vdec(C) brcmutil gpu_sched
> cfg80211 dwmac_generic videobuf2_dma_contig v4l2_mem2mem
> videobuf2_memops videobuf2_v4l2 videobuf2_common drm_shmem_helper
> ecdh_generic dwmac_meson8b stmmac_platform dw_hdmi_i2s_audio stmmac
> ecc ir_nec_decoder videodev pcs_xpcs rc_khadas phylink ptp
> meson_saradc meson_ir rc_core snd_soc_meson_axg_frddr
> snd_soc_meson_axg_toddr snd_soc_meson_axg_sound_card
> snd_soc_meson_card_utils snd_soc_meson_axg_fifo ao_cec_g12a
> snd_soc_meson_axg_tdmin snd_soc_meson_axg_tdm_interface
> snd_soc_meson_axg_tdmout snd_soc_meson_axg_tdm_formatter rfkill
> meson_gxbb_wdt fuse crypto_user uas usb_storage adc_keys
> gpio_keys_polled industrialio
> [  103.857778] CPU: 3 PID: 530 Comm: pipewire-media- Tainted: G
>  C        5.16.0-ARCH+ #8

Any chance you could try with an updated kernel? It's not clear to me if
this is a real issue or just a warning that's been fixed since by commit.

bbf7d3b1c4f40 "ASoC: soc-pcm: Fix DPCM lockdep warning due to nested
stream locks"

You may also want to open a bugzilla issue to attach the full dmesg log
and your configuration reported by 'alsa-info'

> [  103.857791] Hardware name: Khadas VIM3 (DT)
> [  103.857795] pstate: 60000005 (nZCv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
> [  103.857801] pc : dpcm_be_connect+0xd0/0x2d0
> [  103.881758] lr : dpcm_add_paths.isra.0+0xcc/0x1e0
> [  103.881763] sp : ffff800009f4b870
> [  103.881765] x29: ffff800009f4b870 x28: ffff000003b944c0 x27: 0000000000000001
> [  103.881772] x26: 0000000000000001 x25: 0000000000000340 x24: 00000000000002a0
> [  103.881777] x23: 0000000000000001 x22: 0000000000000000 x21: ffff000005b18080
> [  103.881782] x20: ffff000005b183c0 x19: ffff000005b1c080 x18: 0000000000000000
> [  103.881787] x17: 0000000000000000 x16: 0000000000000000 x15: 0000000000000000
> [  103.881792] x14: 0000000000000000 x13: 0000000000000000 x12: 0000000000000000
> [  103.881797] x11: 0000000000000000 x10: 0000000000000000 x9 : 0000000000000000
> [  103.881801] x8 : ffff000012020400 x7 : 0000000000000000 x6 : 000000000000003f
> [  103.881806] x5 : 0000000000000040 x4 : 0000000000000000 x3 : ffff000002822000
> [  103.881811] x2 : ffff000002861b50 x1 : ffff00000282f800 x0 : ffff000002861800
> [  103.881817] Call trace:
> [  103.881819]  dpcm_be_connect+0xd0/0x2d0
> [  103.881823]  dpcm_add_paths.isra.0+0xcc/0x1e0
> [  103.881826]  dpcm_fe_dai_open+0x80/0x194
> [  103.881831]  snd_pcm_open_substream+0xa4/0x174
> [  103.881838]  snd_pcm_open.part.0+0xd8/0x1dc
> [  103.881843]  snd_pcm_capture_open+0x64/0x94
> [  103.992730]  snd_open+0xac/0x1d0
> [  103.992739]  chrdev_open+0xe0/0x2d0
> [  103.999389]  do_dentry_open+0x12c/0x3a0
> [  103.999397]  vfs_open+0x30/0x3c
> [  103.999401]  do_open+0x204/0x414
> [  103.999405]  path_openat+0x10c/0x27c
> [  103.999408]  do_filp_open+0x80/0x130
> [  103.999412]  do_sys_openat2+0xb4/0x170
> [  103.999414]  __arm64_sys_openat+0x64/0xb0
> [  103.999416]  invoke_syscall+0x48/0x114
> [  103.999426]  el0_svc_common.constprop.0+0xd4/0xfc
> [  103.999429]  do_el0_svc+0x28/0x90
> [  103.999432]  el0_svc+0x28/0x80
> 
> After I removed pipewire software the error message was
> disappear. But the sound and media still doesn't working and alsamixer
> cannot change sound card to my usb audio device.

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-06-27 15:12 ` Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device Pierre-Louis Bossart
@ 2022-06-27 16:52   ` Mark Brown
       [not found]   ` <CADs9LoN-L0X1Dr1sP2K7xrcWm7dpHW6MhF47c2eBB0moLNnPRQ@mail.gmail.com>
  1 sibling, 0 replies; 11+ messages in thread
From: Mark Brown @ 2022-06-27 16:52 UTC (permalink / raw)
  To: Pierre-Louis Bossart
  Cc: ALSA Development Mailing List, Alex Natalsson, Kai Vehmanen,
	Takashi Iwai, Ranjani Sridharan, linux-sound, Bard Liao

[-- Attachment #1: Type: text/plain, Size: 514 bytes --]

On Mon, Jun 27, 2022 at 10:12:37AM -0500, Pierre-Louis Bossart wrote:
> On 6/26/22 04:29, Alex Natalsson wrote:
> > Hello friends.

> > The log after alsamixer launch:
> > [  103.786358] Internal error: Oops: 96000004 [#1] SMP
> > [  103.786509] Modules linked in: snd_seq_dummy snd_hrtimer snd_seq

> Any chance you could try with an updated kernel? It's not clear to me if
> this is a real issue or just a warning that's been fixed since by commit.

That's an oops, and the userspace applications are hanging...

[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 488 bytes --]

^ permalink raw reply	[flat|nested] 11+ messages in thread

[parent not found: <CADs9LoN-L0X1Dr1sP2K7xrcWm7dpHW6MhF47c2eBB0moLNnPRQ@mail.gmail.com>]

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
       [not found]   ` <CADs9LoN-L0X1Dr1sP2K7xrcWm7dpHW6MhF47c2eBB0moLNnPRQ@mail.gmail.com>
@ 2022-06-28 22:47     ` Pierre-Louis Bossart
       [not found]       ` <CADs9LoOZjK=cUuNSEELtgxYoA+yHVFKM_Y9YLcY74smqx8XsjQ@mail.gmail.com>
  0 siblings, 1 reply; 11+ messages in thread
From: Pierre-Louis Bossart @ 2022-06-28 22:47 UTC (permalink / raw)
  To: Alex Natalsson
  Cc: ALSA Development Mailing List, Kai Vehmanen, Takashi Iwai,
	Ranjani Sridharan, linux-sound, Mark Brown, Bard Liao



On 6/28/22 17:10, Alex Natalsson wrote:
>> Any chance you could try with an updated kernel? It's not clear to me if
>> this is a real issue or just a warning that's been fixed since by commit.
> 
> I luanching 5.19-rc2 and finding this problem. Then I used git bisect
> and found this commit, which broke my audio system. I don't know maybe
> I have wrong settings, but for me this real problem because sound not
> working....
> 
>> You may also want to open a bugzilla issue
> Yes, I can. If problem not in my config settings.

It's great that you've been able to bisect.

What's surprising is that you have a long list of errors reported before
the kernel oops, e.g.

[  243.188688]  fe.dai-link-1: ASoC: no backend DAIs enabled for
fe.dai-link-1

[  243.188702]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)

[  243.188919]  fe.dai-link-1: ASoC: no backend DAIs enabled for
fe.dai-link-1

[  243.188922]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)

Did those appear with this commit? That's usually a sign of missing
mixer settings to connect FE and BE, not sure how this would appear
because of change in the locking.

It would also help if you could add dev_err logs in dpcm_be_connect() to
see in which code section the oops occurs. In theory we have all kinds
of checks before calling dpcm_be_connect() but we've obviously missing
something.

^ permalink raw reply	[flat|nested] 11+ messages in thread

[parent not found: <CADs9LoOZjK=cUuNSEELtgxYoA+yHVFKM_Y9YLcY74smqx8XsjQ@mail.gmail.com>]

[parent not found: <c554b69c-0c73-158d-85d8-95a0375babeb@linux.intel.com>]

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
       [not found]         ` <c554b69c-0c73-158d-85d8-95a0375babeb@linux.intel.com>
@ 2022-07-01 15:47           ` Alex Natalsson
  2022-07-01 16:42             ` Pierre-Louis Bossart
  0 siblings, 1 reply; 11+ messages in thread
From: Alex Natalsson @ 2022-07-01 15:47 UTC (permalink / raw)
  To: Pierre-Louis Bossart, Kai Vehmanen, Bard Liao, Ranjani Sridharan,
	Takashi Iwai, Mark Brown, linux-sound,
	ALSA Development Mailing List

[-- Attachment #1: Type: text/plain, Size: 488 bytes --]

> Wow. So there's already a problem with a config and we made it worse...
>
> Can you try with this hack attached, just to see what causes the kernel
> oops?  Thanks!

Hello friends)
I modified your hack a little. And I don't understanding what is fe
and be, but I see what problem apearing due to be_substream points to
0.
The
"if (!fe_substream->pcm->nonatomic && be_substream->pcm->nonatomic) {"
at result gives the error...

* I am sorry what delete recipients in the previous send((

[-- Attachment #2: dmesg.log_dpcm_my --]
[-- Type: application/octet-stream, Size: 53532 bytes --]

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.19.0-rc2-ARCH+ (leha@VIM3) (gcc (GCC) 12.1.0, GNU ld (GNU Binutils) 2.38) #19 SMP Fri Jul 1 18:09:54 MSK 2022
[    0.000000] Machine model: Khadas VIM3
[    0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'secmon@5000000': base 0x0000000005000000, size 3 MiB
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000e5000000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000f4ffffff]
[    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 0x0000000000000000-0x00000000052fffff]
[    0.000000]   node   0: [mem 0x0000000005300000-0x00000000072fffff]
[    0.000000]   node   0: [mem 0x0000000007300000-0x00000000f4ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000f4ffffff]
[    0.000000] On node 0, zone DMA: 12288 pages in unavailable ranges
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.0 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.1
[    0.000000] percpu: Embedded 20 pages/cpu s42664 r8192 d31064 u81920
[    0.000000] pcpu-alloc: s42664 r8192 d31064 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 987840
[    0.000000] Kernel command line: root=UUID=11159f50-c9f8-4857-9e6a-ddb1c9f43298
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 3607908K/4014080K available (12800K kernel code, 1964K rwdata, 4124K rodata, 2624K init, 854K bss, 144028K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000217] Console: colour dummy device 80x25
[    0.000461] printk: console [tty0] enabled
[    0.000500] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=24000)
[    0.000520] pid_max: default: 32768 minimum: 301
[    0.000679] LSM: Security Framework initializing
[    0.000700] Yama: becoming mindful.
[    0.000913] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.000942] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.003011] cblist_init_generic: Setting adjustable number of callback queues.
[    0.003049] cblist_init_generic: Setting shift to 3 and lim to 1.
[    0.003281] rcu: Hierarchical SRCU implementation.
[    0.003328] printk: console [tty0] printing thread started
[    0.006091] smp: Bringing up secondary CPUs ...
[    0.006768] Detected VIPT I-cache on CPU1
[    0.006829] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.008104] CPU features: detected: Spectre-v2
[    0.008115] CPU features: detected: Spectre-v4
[    0.008119] CPU features: detected: Spectre-BHB
[    0.008123] CPU features: detected: ARM erratum 858921
[    0.008129] Detected VIPT I-cache on CPU2
[    0.008157] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.008172] arch_timer: CPU2: Trapping CNTVCT access
[    0.008181] CPU2: Booted secondary processor 0x0000000100 [0x410fd092]
[    0.008886] Detected VIPT I-cache on CPU3
[    0.008905] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.008913] arch_timer: CPU3: Trapping CNTVCT access
[    0.008917] CPU3: Booted secondary processor 0x0000000101 [0x410fd092]
[    0.009676] Detected VIPT I-cache on CPU4
[    0.009695] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.009702] arch_timer: CPU4: Trapping CNTVCT access
[    0.009707] CPU4: Booted secondary processor 0x0000000102 [0x410fd092]
[    0.010437] Detected VIPT I-cache on CPU5
[    0.010456] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.010463] arch_timer: CPU5: Trapping CNTVCT access
[    0.010468] CPU5: Booted secondary processor 0x0000000103 [0x410fd092]
[    0.010543] smp: Brought up 1 node, 6 CPUs
[    0.010552] SMP: Total of 6 processors activated.
[    0.010557] CPU features: detected: 32-bit EL0 Support
[    0.010559] CPU features: detected: 32-bit EL1 Support
[    0.010564] CPU features: detected: CRC32 instructions
[    0.011306] CPU: All CPU(s) started at EL2
[    0.011364] alternatives: patching kernel code
[    0.013396] devtmpfs: initialized
[    0.020448] Registered cp15_barrier emulation handler
[    0.020460] Registered setend emulation handler
[    0.020472] KASLR disabled due to lack of seed
[    0.020646] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.020658] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.024636] pinctrl core: initialized pinctrl subsystem
[    0.025556] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.026589] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.026897] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.027150] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.027245] audit: initializing netlink subsys (disabled)
[    0.027382] audit: type=2000 audit(0.026:1): state=initialized audit_enabled=0 res=1
[    0.027669] thermal_sys: Registered thermal governor 'fair_share'
[    0.027672] thermal_sys: Registered thermal governor 'bang_bang'
[    0.027675] thermal_sys: Registered thermal governor 'step_wise'
[    0.027677] thermal_sys: Registered thermal governor 'user_space'
[    0.027679] thermal_sys: Registered thermal governor 'power_allocator'
[    0.028070] cpuidle: using governor ladder
[    0.028082] cpuidle: using governor menu
[    0.028168] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.028271] ASID allocator initialised with 65536 entries
[    0.028286] HugeTLB: can optimize 4095 vmemmap pages for hugepages-1048576kB
[    0.028289] HugeTLB: can optimize 127 vmemmap pages for hugepages-32768kB
[    0.028293] HugeTLB: can optimize 7 vmemmap pages for hugepages-2048kB
[    0.028296] HugeTLB: can optimize 0 vmemmap pages for hugepages-64kB
[    0.029032] Serial: AMBA PL011 UART driver
[    0.039212] platform ff900000.vpu: Fixing up cyclic dependency with ff600000.hdmi-tx
[    0.044195] platform hdmi-connector: Fixing up cyclic dependency with ff600000.hdmi-tx
[    0.049485] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.049493] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.049497] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.049500] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.049815] cryptd: max_cpu_qlen set to 1000
[    0.051029] iommu: Default domain type: Translated 
[    0.051033] iommu: DMA domain TLB invalidation policy: strict mode 
[    0.051533] SCSI subsystem initialized
[    0.051657] usbcore: registered new interface driver usbfs
[    0.051688] usbcore: registered new interface driver hub
[    0.051707] usbcore: registered new device driver usb
[    0.051844] mc: Linux media interface: v0.10
[    0.051899] pps_core: LinuxPPS API ver. 1 registered
[    0.051901] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.051996] EDAC MC: Ver: 3.0.0
[    0.052569] Advanced Linux Sound Architecture Driver Initialized.
[    0.053012] NetLabel: Initializing
[    0.053014] NetLabel:  domain hash size = 128
[    0.053017] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.053051] NetLabel:  unlabeled traffic allowed by default
[    0.053176] vgaarb: loaded
[    0.053342] clocksource: Switched to clocksource arch_sys_counter
[    0.053540] VFS: Disk quotas dquot_6.6.0
[    0.053581] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.053705] FS-Cache: Loaded
[    0.053856] CacheFiles: Loaded
[    0.058471] NET: Registered PF_INET protocol family
[    0.058668] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.061528] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.061555] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.061588] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.061732] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.061919] TCP: Hash tables configured (established 32768 bind 32768)
[    0.062079] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)
[    0.062140] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.062175] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.062356] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.062376] PCI: CLS 0 bytes, default 64
[    0.062708] Trying to unpack rootfs image as initramfs...
[    0.063154] kvm [1]: IPA Size Limit: 40 bits
[    0.069946] kvm [1]: vgic interrupt IRQ9
[    0.070656] kvm [1]: Hyp mode initialized successfully
[    0.071843] Initialise system trusted keyrings
[    0.071966] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    0.075576] zbud: loaded
[    0.104888] NET: Registered PF_ALG protocol family
[    0.104902] xor: measuring software checksum speed
[    0.107304]    8regs           :  4145 MB/sec
[    0.109625]    32regs          :  4260 MB/sec
[    0.112786]    arm64_neon      :  3118 MB/sec
[    0.112789] xor: using function: 32regs (4260 MB/sec)
[    0.112796] Key type asymmetric registered
[    0.112799] Asymmetric key parser 'x509' registered
[    0.112859] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
[    0.113089] io scheduler mq-deadline registered
[    0.113094] io scheduler kyber registered
[    0.113171] io scheduler bfq registered
[    0.117563] irq_meson_gpio: 100 to 8 gpio interrupt mux initialized
[    0.128467] soc soc0: Amlogic Meson G12B (A311D) Revision 29:b (10:2) Detected
[    0.130197] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.131643] ff803000.serial: ttyAML0 at MMIO 0xff803000 (irq = 14, base_baud = 1500000) is a meson_uart
[    0.131754] printk: console [ttyAML0] enabled
[    0.131758] printk: console [ttyAML0] printing thread started
[    0.132259] ffd24000.serial: ttyAML6 at MMIO 0xffd24000 (irq = 15, base_baud = 1500000) is a meson_uart
[    0.132359] serial serial0: tty port ttyAML6 registered
[    0.147224] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.147242] ehci-pci: EHCI PCI platform driver
[    0.147265] ehci-platform: EHCI generic platform driver
[    0.147378] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.147386] ohci-pci: OHCI PCI platform driver
[    0.147408] ohci-platform: OHCI generic platform driver
[    0.147491] uhci_hcd: USB Universal Host Controller Interface driver
[    0.147770] SPI driver max3421-hcd has no spi_device_id for maxim,max3421
[    0.147943] mousedev: PS/2 mouse device common for all mice
[    0.148351] meson-vrtc ff8000a8.rtc: registered as rtc1
[    0.150865] device-mapper: uevent: version 1.0.3
[    0.151053] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com
[    0.153662] cpufreq: cpufreq_online: CPU2: Running at unlisted initial frequency: 999999 KHz, changing to: 1000000 KHz
[    0.155255] meson-gx-mmc ffe05000.sd: Got CD GPIO
[    0.159581] ledtrig-cpu: registered to indicate activity on CPUs
[    0.159895] meson-sm: secure-monitor enabled
[    0.160007] hid: raw HID events driver (C) Jiri Kosina
[    0.160181] usbcore: registered new interface driver usbhid
[    0.160183] usbhid: USB HID core driver
[    0.161525] Initializing XFRM netlink socket
[    0.161709] NET: Registered PF_INET6 protocol family
[    0.236023] Freeing initrd memory: 7260K
[    0.245135] Segment Routing with IPv6
[    0.245169] In-situ OAM (IOAM) with IPv6
[    0.245212] mip6: Mobile IPv6
[    0.245215] NET: Registered PF_PACKET protocol family
[    0.245304] Key type dns_resolver registered
[    0.245499] registered taskstats version 1
[    0.245506] Loading compiled-in X.509 certificates
[    0.245851] zswap: loaded using pool lzo/zbud
[    0.245956] debug_vm_pgtable: [debug_vm_pgtable         ]: Validating architecture page table helpers
[    0.246116] Key type ._fscrypt registered
[    0.246118] Key type .fscrypt registered
[    0.246119] Key type fscrypt-provisioning registered
[    0.246367] Key type encrypted registered
[    0.831129] meson-drm ff900000.vpu: Queued 1 outputs on vpu
[    0.831692] meson-drm ff900000.vpu: CVBS Output connector not available
[    0.853362] meson-dw-hdmi ff600000.hdmi-tx: Detected HDMI TX controller v2.01a with HDCP (meson_dw_hdmi_phy)
[    0.853495] meson-dw-hdmi ff600000.hdmi-tx: registered DesignWare HDMI I2C bus driver
[    0.853564] meson-drm ff900000.vpu: bound ff600000.hdmi-tx (ops 0xffff800008d8db98)
[    0.853780] [drm] Initialized meson 1.0.0 20161109 for ff900000.vpu on minor 0
[    0.853818] meson-drm ff900000.vpu: [drm] Cannot find any crtc or sizes
[    0.854566] dwc3-meson-g12a ffe09000.usb: USB2 ports: 2
[    0.854569] dwc3-meson-g12a ffe09000.usb: USB3 ports: 1
[    0.857273] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    0.857281] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
[    0.857385] xhci-hcd xhci-hcd.2.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x0000000000010010
[    0.857407] xhci-hcd xhci-hcd.2.auto: irq 20, io mem 0xff500000
[    0.857468] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    0.857472] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[    0.857476] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
[    0.857536] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.19
[    0.857539] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.857541] usb usb1: Product: xHCI Host Controller
[    0.857543] usb usb1: Manufacturer: Linux 5.19.0-rc2-ARCH+ xhci-hcd
[    0.857545] usb usb1: SerialNumber: xhci-hcd.2.auto
[    0.857738] hub 1-0:1.0: USB hub found
[    0.857748] hub 1-0:1.0: 2 ports detected
[    0.857878] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    0.857908] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.19
[    0.857911] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.857913] usb usb2: Product: xHCI Host Controller
[    0.857915] usb usb2: Manufacturer: Linux 5.19.0-rc2-ARCH+ xhci-hcd
[    0.857917] usb usb2: SerialNumber: xhci-hcd.2.auto
[    0.858047] hub 2-0:1.0: USB hub found
[    0.858054] hub 2-0:1.0: 1 port detected
[    0.858305] pca953x 0-0020: using no AI
[    0.859561] meson-gx-mmc ffe03000.sd: allocated mmc-pwrseq
[    0.861489] meson-gx-mmc ffe07000.mmc: allocated mmc-pwrseq
[    0.862398] ALSA device list:
[    0.862405]   No soundcards found.
[    0.909628] Freeing unused kernel memory: 2624K
[    1.000843] mmc2: new high speed SDIO card at address 0001
[    1.099339] usb 1-1: new high-speed USB device number 2 using xhci-hcd
[    1.121380] Run /init as init process
[    1.121382]   with arguments:
[    1.121383]     /init
[    1.121385]   with environment:
[    1.121386]     HOME=/
[    1.121387]     TERM=linux
[    1.205751] mmc1: new HS200 MMC card at address 0001
[    1.206737] mmcblk1: mmc1:0001 BJTD4R 29.1 GiB 
[    1.208595] mmcblk1boot0: mmc1:0001 BJTD4R 4.00 MiB 
[    1.210155] mmcblk1boot1: mmc1:0001 BJTD4R 4.00 MiB 
[    1.212101] mmcblk1rpmb: mmc1:0001 BJTD4R 4.00 MiB, chardev (238:0)
[    1.225581] usb 1-1: New USB device found, idVendor=1a40, idProduct=0801, bcdDevice= 1.00
[    1.225589] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.225592] usb 1-1: Product: USB 2.0 Hub
[    1.273722] hub 1-1:1.0: USB hub found
[    1.273766] hub 1-1:1.0: 4 ports detected
[    1.348566] input: gpio-keys-polled as /devices/platform/gpio-keys-polled/input/input0
[    1.522392] usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
[    1.535164] usb 2-1: New USB device found, idVendor=0bc2, idProduct=2322, bcdDevice= 1.00
[    1.535172] usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    1.535176] usb 2-1: Product: Expansion
[    1.535179] usb 2-1: Manufacturer: Seagate
[    1.535182] usb 2-1: SerialNumber: NAC2E2ZQ
[    1.538989] usbcore: registered new interface driver usb-storage
[    1.546308] scsi host0: uas
[    1.546524] usbcore: registered new interface driver uas
[    1.547328] scsi 0:0:0:0: Direct-Access     Seagate  Expansion        9300 PQ: 0 ANSI: 6
[    1.548676] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    1.549191] sd 0:0:0:0: [sda] Spinning up disk...
[    1.622811] usb 1-1.2: new high-speed USB device number 3 using xhci-hcd
[    1.733552] usb 1-1.2: New USB device found, idVendor=214b, idProduct=7250, bcdDevice= 1.00
[    1.733559] usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.733562] usb 1-1.2: Product: USB2.0 HUB
[    1.785591] hub 1-1.2:1.0: USB hub found
[    1.785627] hub 1-1.2:1.0: 4 ports detected
[    2.056344] usb 1-1.2.2: new low-speed USB device number 4 using xhci-hcd
[    2.137562] usb 1-1.2.2: New USB device found, idVendor=1a2c, idProduct=0e24, bcdDevice= 1.10
[    2.137566] usb 1-1.2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.137569] usb 1-1.2.2: Product: USB Keyboard
[    2.137571] usb 1-1.2.2: Manufacturer: SEM
[    2.236044] input: SEM USB Keyboard as /devices/platform/soc/ffe09000.usb/ff500000.usb/xhci-hcd.2.auto/usb1/1-1/1-1.2/1-1.2.2/1-1.2.2:1.0/0003:1A2C:0E24.0001/input/input1
[    2.287511] hid-generic 0003:1A2C:0E24.0001: input,hidraw0: USB HID v1.10 Keyboard [SEM USB Keyboard] on usb-xhci-hcd.2.auto-1.2.2/input0
[    2.295437] input: SEM USB Keyboard Consumer Control as /devices/platform/soc/ffe09000.usb/ff500000.usb/xhci-hcd.2.auto/usb1/1-1/1-1.2/1-1.2.2/1-1.2.2:1.1/0003:1A2C:0E24.0002/input/input2
[    2.347488] input: SEM USB Keyboard System Control as /devices/platform/soc/ffe09000.usb/ff500000.usb/xhci-hcd.2.auto/usb1/1-1/1-1.2/1-1.2.2/1-1.2.2:1.1/0003:1A2C:0E24.0002/input/input3
[    2.347596] hid-generic 0003:1A2C:0E24.0002: input,hidraw1: USB HID v1.10 Device [SEM USB Keyboard] on usb-xhci-hcd.2.auto-1.2.2/input1
[    2.412347] usb 1-1.2.3: new high-speed USB device number 5 using xhci-hcd
[    2.498884] usb 1-1.2.3: New USB device found, idVendor=148f, idProduct=760b, bcdDevice= 0.00
[    2.498888] usb 1-1.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.498891] usb 1-1.2.3: Product: 802.11 n WLAN
[    2.498893] usb 1-1.2.3: Manufacturer: MediaTek
[    2.498895] usb 1-1.2.3: SerialNumber: 1.0
[    2.524208] Console: switching to colour frame buffer device 160x64
[    2.540809] meson-drm ff900000.vpu: [drm] fb0: mesondrmfb frame buffer device
[    2.564343] usb 1-1.2.4: new full-speed USB device number 6 using xhci-hcd
[    2.592353] .
[    2.643050] usb 1-1.2.4: New USB device found, idVendor=1b3f, idProduct=2008, bcdDevice= 1.00
[    2.643054] usb 1-1.2.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.643057] usb 1-1.2.4: Product: USB Audio Device
[    2.643058] usb 1-1.2.4: Manufacturer: GeneralPlus
[    2.682347] input: GeneralPlus USB Audio Device as /devices/platform/soc/ffe09000.usb/ff500000.usb/xhci-hcd.2.auto/usb1/1-1/1-1.2/1-1.2.4/1-1.2.4:1.3/0003:1B3F:2008.0003/input/input4
[    2.734433] hid-generic 0003:1B3F:2008.0003: input,hidraw2: USB HID v2.01 Device [GeneralPlus USB Audio Device] on usb-xhci-hcd.2.auto-1.2.4/input3
[    3.616354] ..ready
[    4.640565] sd 0:0:0:0: [sda] 3907029167 512-byte logical blocks: (2.00 TB/1.82 TiB)
[    4.771223] sd 0:0:0:0: [sda] Write Protect is off
[    4.771229] sd 0:0:0:0: [sda] Mode Sense: 4f 00 00 00
[    4.771434] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.771583] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
[    4.771588] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[    4.824160]  sda: sda1 sda2 sda3 sda4 < sda5 >
[    4.824509] sd 0:0:0:0: [sda] Attached SCSI disk
[    6.918897] random: crng init done
[    9.802580] EXT4-fs (sda2): mounting ext3 file system using the ext4 subsystem
[   10.939496] EXT4-fs (sda2): mounted filesystem with ordered data mode. Quota mode: none.
[   12.544626] systemd[1]: System time before build time, advancing clock.
[   12.773701] systemd[1]: systemd 251.2-1-arch 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
[   12.774225] systemd[1]: Detected architecture arm64.
[   12.841203] systemd[1]: Hostname set to <VIM3>.
[   14.545651] systemd[1]: Queued start job for default target Graphical Interface.
[   14.547315] systemd[1]: Created slice Slice /system/getty.
[   14.567889] systemd[1]: Created slice Slice /system/modprobe.
[   14.582832] systemd[1]: Created slice Slice /system/netctl.
[   14.596810] systemd[1]: Created slice Slice /system/serial-getty.
[   14.611821] systemd[1]: Created slice Slice /system/systemd-fsck.
[   14.626706] systemd[1]: Created slice User and Session Slice.
[   14.640480] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   14.659427] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[   14.675620] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   14.693448] systemd[1]: Reached target Local Encrypted Volumes.
[   14.707391] systemd[1]: Reached target Local Integrity Protected Volumes.
[   14.722406] systemd[1]: Reached target Path Units.
[   14.734380] systemd[1]: Reached target Remote File Systems.
[   14.748369] systemd[1]: Reached target Slice Units.
[   14.760399] systemd[1]: Reached target Local Verity Protected Volumes.
[   14.775501] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[   14.790558] systemd[1]: Listening on LVM2 poll daemon socket.
[   14.806507] systemd[1]: Listening on Process Core Dump Socket.
[   14.820606] systemd[1]: Listening on Journal Audit Socket.
[   14.834530] systemd[1]: Listening on Journal Socket (/dev/log).
[   14.848521] systemd[1]: Listening on Journal Socket.
[   14.860546] systemd[1]: Listening on Network Service Netlink Socket.
[   15.027295] systemd[1]: Listening on udev Control Socket.
[   15.041504] systemd[1]: Listening on udev Kernel Socket.
[   15.056829] systemd[1]: Mounting Huge Pages File System...
[   15.069765] systemd[1]: Mounting POSIX Message Queue File System...
[   15.085842] systemd[1]: Mounting Kernel Debug File System...
[   15.097563] systemd[1]: Kernel Trace File System was skipped because of a failed condition check (ConditionPathExists=/sys/kernel/tracing).
[   15.099492] systemd[1]: Starting Create List of Static Device Nodes...
[   15.126953] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[   15.147116] systemd[1]: Starting Load Kernel Module configfs...
[   15.163075] systemd[1]: Starting Load Kernel Module drm...
[   15.178086] systemd[1]: Starting Load Kernel Module fuse...
[   15.192563] systemd[1]: Starting File System Check on Root Device...
[   15.305973] systemd[1]: Starting Load Kernel Modules...
[   15.306501] systemd[1]: Repartition Root Disk was skipped because all trigger condition checks failed.
[   15.310630] systemd[1]: Starting Coldplug All udev Devices...
[   15.330768] systemd[1]: Finished Create List of Static Device Nodes.
[   15.332238] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[   15.332932] systemd[1]: Finished Load Kernel Module configfs.
[   15.345216] systemd[1]: modprobe@drm.service: Deactivated successfully.
[   15.345872] systemd[1]: Finished Load Kernel Module drm.
[   15.360082] systemd[1]: Mounting Kernel Configuration File System...
[   15.441537] systemd[1]: Finished Load Kernel Modules.
[   15.445368] systemd[1]: Starting Apply Kernel Variables...
[   15.505349] fuse: init (API version 7.36)
[   15.507225] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[   15.507897] systemd[1]: Finished Load Kernel Module fuse.
[   15.520176] systemd[1]: Mounting FUSE Control File System...
[   15.544518] systemd[1]: Mounted Huge Pages File System.
[   15.545085] systemd[1]: Mounted POSIX Message Queue File System.
[   15.545698] systemd[1]: Mounted Kernel Debug File System.
[   15.556785] systemd[1]: Mounted Kernel Configuration File System.
[   15.568126] systemd[1]: Mounted FUSE Control File System.
[   15.602818] systemd[1]: Finished Apply Kernel Variables.
[   15.745739] systemd[1]: Finished Coldplug All udev Devices.
[   18.234308] systemd[1]: Finished File System Check on Root Device.
[   18.252521] systemd[1]: Starting Remount Root and Kernel File Systems...
[   18.523652] EXT4-fs (sda2): re-mounted. Quota mode: none.
[   18.526517] systemd[1]: Finished Remount Root and Kernel File Systems.
[   18.544887] systemd[1]: First Boot Wizard was skipped because of a failed condition check (ConditionFirstBoot=yes).
[   18.586018] systemd[1]: Rebuild Hardware Database was skipped because all trigger condition checks failed.
[   18.587696] systemd[1]: Starting Load/Save Random Seed...
[   18.607943] systemd[1]: Starting Create System Users...
[   18.619097] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
[   18.804188] systemd[1]: Finished Load/Save Random Seed.
[   18.815608] systemd[1]: First Boot Complete was skipped because of a failed condition check (ConditionFirstBoot=yes).
[   19.137025] systemd[1]: Finished Create System Users.
[   19.152029] systemd[1]: Starting Create Static Device Nodes in /dev...
[   19.470064] systemd[1]: Finished Create Static Device Nodes in /dev.
[   19.486644] systemd[1]: Reached target Preparation for Local File Systems.
[   19.502434] systemd[1]: Virtual Machine and Container Storage (Compatibility) was skipped because of a failed condition check (ConditionPathExists=/var/lib/machines.raw).
[   19.502463] systemd[1]: Entropy Daemon based on the HAVEGE algorithm was skipped because of a failed condition check (ConditionKernelVersion=<5.6).
[   19.526875] systemd[1]: Starting Journal Service...
[   19.545801] systemd[1]: Starting Rule-based Manager for Device Events and Files...
[   19.634061] systemd[1]: Started Journal Service.
[   19.644559] audit: type=1130 audit(1654261172.100:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   19.894075] systemd-journald[316]: Received client request to flush runtime journal.
[   21.266467] systemd-journald[316]: File /var/log/journal/93b91e738efc4fa98971be34fbef1960/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   22.101636] audit: type=1130 audit(1654261174.557:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   22.118489] audit: type=1334 audit(1654261174.572:4): prog-id=9 op=LOAD
[   22.471884] input: adc-keys as /devices/platform/adc-keys/input/input5
[   22.706684] PTP clock support registered
[   22.720047] debugfs: Directory 'ff800280.cec' with parent 'regmap' already present!
[   22.785366] Registered IR keymap rc-khadas
[   22.788926] IR NEC protocol handler initialized
[   22.811458] rc rc0: meson-ir as /devices/platform/soc/ff800000.bus/ff808000.ir/rc/rc0
[   22.811569] rc rc0: lirc_dev: driver meson-ir registered at minor = 0, raw IR receiver, no transmitter
[   22.811658] input: meson-ir as /devices/platform/soc/ff800000.bus/ff808000.ir/rc/rc0/input6
[   22.811878] meson-ir ff808000.ir: receiver initialized
[   22.824296] panfrost ffe40000.gpu: clock rate = 24000000
[   22.824375] panfrost ffe40000.gpu: error -ENODEV: dev_pm_opp_set_regulators: no regulator (mali) found
[   22.825062] panfrost ffe40000.gpu: mali-g52 id 0x7212 major 0x0 minor 0x0 status 0x0
[   22.825068] panfrost ffe40000.gpu: features: 00000000,00000cf7, issues: 00000000,00000400
[   22.825071] panfrost ffe40000.gpu: Features: L2:0x07110206 Shader:0x00000000 Tiler:0x00000809 Mem:0x1 MMU:0x00002830 AS:0xff JS:0x7
[   22.825075] panfrost ffe40000.gpu: shader_present=0x3 l2_present=0x1
[   22.839613] [drm] Initialized panfrost 1.2.0 20180908 for ffe40000.gpu on minor 1
[   22.914296] meson8b-dwmac ff3f0000.ethernet: IRQ eth_wake_irq not found
[   22.914303] meson8b-dwmac ff3f0000.ethernet: IRQ eth_lpi not found
[   22.914507] meson8b-dwmac ff3f0000.ethernet: PTP uses main clock
[   22.915204] meson8b-dwmac ff3f0000.ethernet: User ID: 0x11, Synopsys ID: 0x37
[   22.915225] meson8b-dwmac ff3f0000.ethernet: 	DWMAC1000
[   22.915229] meson8b-dwmac ff3f0000.ethernet: DMA HW capability register supported
[   22.915233] meson8b-dwmac ff3f0000.ethernet: RX Checksum Offload Engine supported
[   22.915236] meson8b-dwmac ff3f0000.ethernet: COE Type 2
[   22.915239] meson8b-dwmac ff3f0000.ethernet: TX Checksum insertion supported
[   22.915242] meson8b-dwmac ff3f0000.ethernet: Wake-Up On Lan supported
[   22.915505] meson8b-dwmac ff3f0000.ethernet: Normal descriptors
[   22.915515] meson8b-dwmac ff3f0000.ethernet: Ring mode enabled
[   22.915518] meson8b-dwmac ff3f0000.ethernet: Enable RX Mitigation via HW Watchdog Timer
[   22.948654] videodev: Linux video capture interface: v2.00
[   23.114218] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   23.182705] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   23.241854] meson_vdec: module is from the staging directory, the quality is unknown, you have been warned.
[   23.393854] usbcore: registered new interface driver snd-usb-audio
[   23.438516] Bluetooth: Core ver 2.22
[   23.438581] NET: Registered PF_BLUETOOTH protocol family
[   23.438583] Bluetooth: HCI device and connection manager initialized
[   23.438593] Bluetooth: HCI socket layer initialized
[   23.438596] Bluetooth: L2CAP socket layer initialized
[   23.438605] Bluetooth: SCO socket layer initialized
[   23.480061] Bluetooth: HCI UART driver ver 2.3
[   23.480071] Bluetooth: HCI UART protocol H4 registered
[   23.480110] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   23.480257] Bluetooth: HCI UART protocol Broadcom registered
[   23.480373] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
[   23.480514] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
[   23.500758] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac4359-sdio for chip BCM4359/9
[   23.501057] usbcore: registered new interface driver brcmfmac
[   23.523353] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[   23.523372] cfg80211: failed to load regulatory.db
[   23.747150] brcmfmac mmc2:0001:1: Direct firmware load for brcm/brcmfmac4359-sdio.khadas,vim3.bin failed with error -2
[   23.747235] brcmfmac mmc2:0001:1: Direct firmware load for brcm/brcmfmac4359-sdio.bin failed with error -2
[   23.792479] audit: type=1130 audit(1654261176.248:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   23.824965] Bluetooth: hci0: BCM: chip id 121
[   23.825245] Bluetooth: hci0: BCM: features 0x0e
[   23.826794] Bluetooth: hci0: BCM4359C0
[   23.826801] Bluetooth: hci0: BCM4359C0 (003.001.006) build 0000
[   23.827039] Bluetooth: hci0: BCM: firmware Patch file not found, tried:
[   23.827044] Bluetooth: hci0: BCM: 'brcm/BCM4359C0.khadas,vim3.hcd'
[   23.827046] Bluetooth: hci0: BCM: 'brcm/BCM4359C0.hcd'
[   23.827048] Bluetooth: hci0: BCM: 'brcm/BCM.khadas,vim3.hcd'
[   23.827050] Bluetooth: hci0: BCM: 'brcm/BCM.hcd'
[   23.876516] usb 1-1.2.3: reset high-speed USB device number 5 using xhci-hcd
[   23.954858] mt7601u 1-1.2.3:1.0: ASIC revision: 76010001 MAC revision: 76010500
[   24.048493] audit: type=1130 audit(1654261176.504:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.059580] mt7601u 1-1.2.3:1.0: Firmware Version: 0.1.00 Build: 7640 Build time: 201302052146____
[   24.267348] meson8b-dwmac ff3f0000.ethernet eth0: PHY [mdio_mux-0.0:00] driver [RTL8211F Gigabit Ethernet] (irq=36)
[   24.285480] meson8b-dwmac ff3f0000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
[   24.296257] meson8b-dwmac ff3f0000.ethernet eth0: No Safety Features support found
[   24.296275] meson8b-dwmac ff3f0000.ethernet eth0: PTP not supported by HW
[   24.298313] meson8b-dwmac ff3f0000.ethernet eth0: configuring for phy/rgmii link mode
[   24.465507] mt7601u 1-1.2.3:1.0: EEPROM ver:0c fae:00
[   24.500846] FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
[   24.587272] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[   24.679943] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   24.680724] usbcore: registered new interface driver mt7601u
[   24.749407] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50
[   25.301208] Adding 4194300k swap on /dev/sda3.  Priority:-2 extents:1 across:4194300k FS
[   26.617221] audit: type=1130 audit(1654261179.072:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   28.780536] audit: type=1130 audit(1654261181.236:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-6eef448f\x2d52ec\x2d458b\x2db71b\x2d3e2bf6ecb042 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   29.103085] EXT4-fs (sda5): mounting ext3 file system using the ext4 subsystem
[   29.714451] audit: type=1131 audit(1654261182.170:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   31.437349] EXT4-fs (sda5): mounted filesystem with ordered data mode. Quota mode: none.
[   31.598545] audit: type=1130 audit(1654261184.054:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-binfmt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.200715] audit: type=1130 audit(1654261184.656:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.228858] audit: type=1334 audit(1654261184.684:12): prog-id=10 op=LOAD
[   32.266764] audit: type=1334 audit(1654261184.722:13): prog-id=11 op=LOAD
[   32.303628] audit: type=1130 audit(1654261184.759:14): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.319292] audit: type=1127 audit(1654261184.774:15): pid=428 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
[   32.364720] audit: type=1130 audit(1654261184.820:16): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.491465] audit: type=1130 audit(1656688725.295:17): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.578497] audit: type=1130 audit(1656688725.382:18): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.616578] audit: type=1130 audit(1656688725.420:19): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   32.811463] audit: type=1130 audit(1656688725.615:20): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   34.190218] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   34.190229] Bluetooth: BNEP filters: protocol multicast
[   34.190241] Bluetooth: BNEP socket layer initialized
[   35.146470] Bluetooth: hci0: Bad flag given (0x1) vs supported (0x0)
[   35.147283] Bluetooth: hci0: Bad flag given (0x1) vs supported (0x0)
[   35.595200] wlan0: authenticate with 0c:37:dc:d1:f2:b8
[   35.595231] wlan0: bad VHT capabilities, disabling VHT
[   35.606026] wlan0: send auth to 0c:37:dc:d1:f2:b8 (try 1/3)
[   35.607845] wlan0: authenticated
[   35.609379] wlan0: associate with 0c:37:dc:d1:f2:b8 (try 1/3)
[   35.612853] wlan0: RX AssocResp from 0c:37:dc:d1:f2:b8 (capab=0x411 status=0 aid=1)
[   35.642039] wlan0: associated
[   35.678923] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   46.144475] kauditd_printk_skb: 23 callbacks suppressed
[   46.144485] audit: type=1131 audit(1656688842.413:44): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=my_crutchs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   65.190487] audit: type=1131 audit(1656688861.459:45): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   65.233486] audit: type=1334 audit(1656688861.502:46): prog-id=0 op=UNLOAD
[   65.233502] audit: type=1334 audit(1656688861.502:47): prog-id=0 op=UNLOAD
[   65.233511] audit: type=1334 audit(1656688861.502:48): prog-id=0 op=UNLOAD
[   95.157218] audit: type=1100 audit(1656688891.425:49): pid=463 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="leha" exe="/usr/bin/login" hostname=VIM3 addr=? terminal=/dev/tty1 res=success'
[   95.161509] audit: type=1101 audit(1656688891.430:50): pid=463 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="leha" exe="/usr/bin/login" hostname=VIM3 addr=? terminal=/dev/tty1 res=success'
[   95.303794] audit: type=1103 audit(1656688891.572:51): pid=463 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="leha" exe="/usr/bin/login" hostname=VIM3 addr=? terminal=/dev/tty1 res=success'
[   95.303996] audit: type=1006 audit(1656688891.572:52): pid=463 uid=0 old-auid=4294967295 auid=1000 tty=tty1 old-ses=4294967295 ses=1 res=1
[   95.304016] audit: type=1300 audit(1656688891.572:52): arch=c00000b7 syscall=64 success=yes exit=4 a0=5 a1=ffffdad33280 a2=4 a3=ffffa2471c60 items=0 ppid=1 pid=463 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty1 ses=1 comm="login" exe="/usr/bin/login" key=(null)
[   95.304033] audit: type=1327 audit(1656688891.572:52): proctitle=2F62696E2F6C6F67696E002D70002D2D
[   95.414653] audit: type=1130 audit(1656688891.683:53): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   95.440282] audit: type=1101 audit(1656688891.708:54): pid=548 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="leha" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   95.440451] audit: type=1103 audit(1656688891.709:55): pid=548 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=? acct="leha" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[   95.440631] audit: type=1006 audit(1656688891.709:56): pid=548 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=2 res=1
[  102.100072] kauditd_printk_skb: 10 callbacks suppressed
[  102.100084] audit: type=1130 audit(1656688898.368:63): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=rtkit-daemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  102.875075] audit: type=1130 audit(1656688899.143:64): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=polkit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  108.019428] dpcm_be_connect: start
[  108.019435] dpcm_be_connect: 1
[  108.019437] dpcm_be_connect: 3
[  108.019438] dpcm_be_connect: 3.1
[  108.019440] dpcm_be_connect: 3.1 fe_substream_addr=128380928
[  108.019444] dpcm_be_connect: 3.1 be_substream_addr=46314496
[  108.019446] dpcm_be_connect: 3.2
[  108.019447] dpcm_be_connect: 4
[  108.019448] dpcm_be_connect: 5
[  108.019450] dpcm_be_connect: 6
[  108.019451] dpcm_be_connect: 7
[  108.019452] dpcm_be_connect: 8
[  108.019453] dpcm_be_connect: 9
[  108.019454] dpcm_be_connect: 10
[  108.019474] dpcm_be_connect: end
[  108.049855] dpcm_be_connect: start
[  108.049864] dpcm_be_connect: 1
[  108.049865] dpcm_be_connect: 3
[  108.049866] dpcm_be_connect: 3.1
[  108.049868] dpcm_be_connect: 3.1 fe_substream_addr=128380928
[  108.049872] dpcm_be_connect: 3.1 be_substream_addr=46314496
[  108.049874] dpcm_be_connect: 3.2
[  108.049875] dpcm_be_connect: 4
[  108.049877] dpcm_be_connect: 5
[  108.049878] dpcm_be_connect: 6
[  108.049879] dpcm_be_connect: 7
[  108.049880] dpcm_be_connect: 8
[  108.049882] dpcm_be_connect: 9
[  108.049883] dpcm_be_connect: 10
[  108.049906] dpcm_be_connect: end
[  108.077447]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.077455]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.077699]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.077703]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.077938]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.077940]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.078145]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.078147]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.078377]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.078380]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.078869]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.078874]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.079083]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.079086]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.079291]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.079293]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.079525]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.079528]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.079736]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.079739]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.080489]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.080494]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.080784]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.080787]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.081068]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.081071]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.081366]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.081369]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.081654]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.081656]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.082469]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.082474]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.082792]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.082795]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.083101]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.083104]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.083422]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.083425]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.083732]  fe.dai-link-1: ASoC: no backend DAIs enabled for fe.dai-link-1
[  108.083735]  fe.dai-link-1: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.084274]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.084278]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.084515]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.084518]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.084728]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.084730]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.084935]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.084937]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.085141]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.085143]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.085660]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.085665]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.085873]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.085875]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.086080]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.086082]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.086286]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.086288]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.086510]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.086513]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.087187]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.087191]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.087499]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.087503]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.087843]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.087847]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.088139]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.088142]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.088429]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.088432]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.089237]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.089242]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.089572]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.089576]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.089876]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.089878]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.090175]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.090177]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.090488]  fe.dai-link-2: ASoC: no backend DAIs enabled for fe.dai-link-2
[  108.090491]  fe.dai-link-2: ASoC: dpcm_fe_dai_prepare() failed (-22)
[  108.090732] dpcm_be_connect: start
[  108.090734] dpcm_be_connect: 1
[  108.090735] dpcm_be_connect: 3
[  108.090737] dpcm_be_connect: 3.1
[  108.090738] dpcm_be_connect: 3.1 fe_substream_addr=128378368
[  108.090740] dpcm_be_connect: 3.1 be_substream_addr=0
[  108.090750] Unable to handle kernel NULL pointer dereference at virtual address 0000000000000000
[  108.090759] Mem abort info:
[  108.090761]   ESR = 0x0000000096000004
[  108.090763]   EC = 0x25: DABT (current EL), IL = 32 bits
[  108.090766]   SET = 0, FnV = 0
[  108.090768]   EA = 0, S1PTW = 0
[  108.090770]   FSC = 0x04: level 0 translation fault
[  108.090773] Data abort info:
[  108.090774]   ISV = 0, ISS = 0x00000004
[  108.090776]   CM = 0, WnR = 0
[  108.090779] user pgtable: 4k pages, 48-bit VAs, pgdp=00000000165af000
[  108.090782] [0000000000000000] pgd=0000000000000000, p4d=0000000000000000
[  108.090790] Internal error: Oops: 96000004 [#1] SMP
[  108.091458] Modules linked in: snd_seq_dummy snd_hrtimer snd_seq bnep nls_utf8 mt7601u brcmfmac hci_uart mac80211 btbcm bluetooth brcmutil snd_usb_audio meson_vdec(C) libarc4 videobuf2_dma_contig v4l2_mem2mem cfg80211 snd_usbmidi_lib videobuf2_memops snd_hwdep videobuf2_v4l2 snd_rawmidi videobuf2_common ecdh_generic ecc joydev snd_seq_device rfkill meson_gxbb_wdt videodev dwmac_generic dwmac_meson8b stmmac_platform stmmac panfrost gpu_sched pcs_xpcs ir_nec_decoder drm_shmem_helper dw_hdmi_i2s_audio phylink rc_khadas ao_cec_g12a ptp meson_ir rc_core meson_saradc snd_soc_meson_axg_sound_card snd_soc_meson_axg_toddr snd_soc_meson_axg_frddr snd_soc_meson_axg_tdmout snd_soc_meson_axg_fifo snd_soc_meson_axg_tdmin snd_soc_meson_card_utils snd_soc_meson_axg_tdm_interface snd_soc_meson_axg_tdm_formatter fuse crypto_user uas usb_storage adc_keys industrialio gpio_keys_polled
[  108.167841] CPU: 4 PID: 568 Comm: pipewire-media- Tainted: G         C        5.19.0-rc2-ARCH+ #19
[  108.175322] Hardware name: Khadas VIM3 (DT)
[  108.179474] pstate: 60000005 (nZCv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[  108.186386] pc : dpcm_be_connect+0x124/0x3c8
[  108.190658] lr : dpcm_be_connect+0x118/0x3c8
[  108.194848] sp : ffff80000a693860
[  108.198129] x29: ffff80000a693860 x28: 0000000000000348 x27: ffff000003f803c8
[  108.205220] x26: ffff000007a6e600 x25: 0000000000000000 x24: 0000000000000001
[  108.212300] x23: ffff800008de27c0 x22: 0000000000000001 x21: ffff800008de2790
[  108.219382] x20: ffff000007b0b080 x19: ffff000003f80080 x18: ffffffffffffffff
[  108.226458] x17: 0000000000000000 x16: 0000000000000000 x15: ffffffffffffffff
[  108.233542] x14: 0000000000000000 x13: 303d726464615f6d x12: 6165727473627573
[  108.240619] x11: 5f656220312e3320 x10: 3a7463656e6e6f63 x9 : 6165727473627573
[  108.247696] x8 : ffff8000093680e8 x7 : ffff80000a693680 x6 : 0000000000000001
[  108.254782] x5 : ffff0000e47cb818 x4 : 0000000000000000 x3 : 0000000000000000
[  108.261860] x2 : 0000000000000000 x1 : ffff000002ea3800 x0 : 0000000000000000
[  108.268941] Call trace:
[  108.271359]  dpcm_be_connect+0x124/0x3c8
[  108.275250]  dpcm_add_paths.isra.0+0x19c/0x1e0
[  108.279663]  dpcm_fe_dai_open+0x80/0x194
[  108.283553]  snd_pcm_open_substream+0xa8/0x170
[  108.287947]  snd_pcm_open.part.0+0xdc/0x204
[  108.292092]  snd_pcm_capture_open+0x64/0x94
[  108.296235]  snd_open+0xac/0x1c0
[  108.299432]  chrdev_open+0xdc/0x2c0
[  108.302886]  do_dentry_open+0x1b4/0x390
[  108.306688]  vfs_open+0x30/0x3c
[  108.309828]  do_open+0x268/0x3d0
[  108.313005]  path_openat+0x128/0x2a0
[  108.316539]  do_filp_open+0x80/0x130
[  108.320076]  do_sys_openat2+0xb4/0x17c
[  108.323818]  __arm64_sys_openat+0x68/0xac
[  108.327765]  invoke_syscall+0x48/0x114
[  108.331479]  el0_svc_common.constprop.0+0xcc/0xec
[  108.336143]  do_el0_svc+0x28/0x34
[  108.339426]  el0_svc+0x2c/0x84
[  108.342451]  el0t_64_sync_handler+0x11c/0x150
[  108.346776]  el0t_64_sync+0x18c/0x190
[  108.350403] Code: 97ffaaee f9400340 395ec400 350001e0 (f9400320) 
[  108.356451] ---[ end trace 0000000000000000 ]---

[-- Attachment #3: dpcm_be_connect.diff_my --]
[-- Type: application/octet-stream, Size: 2514 bytes --]

diff --git a/sound/soc/soc-pcm.c b/sound/soc/soc-pcm.c
index a827cc3c158a..d90b60814579 100644
--- a/sound/soc/soc-pcm.c
+++ b/sound/soc/soc-pcm.c
@@ -1192,28 +1192,42 @@ static int dpcm_be_connect(struct snd_soc_pcm_runtime *fe,
 	struct snd_pcm_substream *be_substream;
 	struct snd_soc_dpcm *dpcm;
 
+	pr_info("%s: start\n", __func__);
+
 	snd_soc_dpcm_mutex_assert_held(fe);
 
+	pr_info("%s: 1\n", __func__);
+
 	/* only add new dpcms */
 	for_each_dpcm_be(fe, stream, dpcm) {
 		if (dpcm->be == be && dpcm->fe == fe)
 			return 0;
 	}
 
+	pr_info("%s: 3\n", __func__);
+
 	fe_substream = snd_soc_dpcm_get_substream(fe, stream);
 	be_substream = snd_soc_dpcm_get_substream(be, stream);
 
+	pr_info("%s: 3.1\n", __func__);
+	pr_info("%s: 3.1 fe_substream_addr=%d\n", __func__,fe_substream);
+	pr_info("%s: 3.1 be_substream_addr=%d\n", __func__,be_substream);
 	if (!fe_substream->pcm->nonatomic && be_substream->pcm->nonatomic) {
 		dev_err(be->dev, "%s: FE is atomic but BE is nonatomic, invalid configuration\n",
 			__func__);
+		pr_info("%s: 3.3; Here \"return -EINVAL\" must be!!\n", __func__);
 		return -EINVAL;
 	}
+	pr_info("%s: 3.2\n", __func__);
 	if (fe_substream->pcm->nonatomic && !be_substream->pcm->nonatomic) {
 		dev_warn(be->dev, "%s: FE is nonatomic but BE is not, forcing BE as nonatomic\n",
 			 __func__);
-		be_substream->pcm->nonatomic = 1;
+		pr_info("%s: 3.5; Here \"be_substream->pcm->nonatomic = 1;\" must be!!\n", __func__);
+//		be_substream->pcm->nonatomic = 1;
 	}
 
+	pr_info("%s: 4\n", __func__);
+
 	dpcm = kzalloc(sizeof(struct snd_soc_dpcm), GFP_KERNEL);
 	if (!dpcm)
 		return -ENOMEM;
@@ -1222,17 +1236,35 @@ static int dpcm_be_connect(struct snd_soc_pcm_runtime *fe,
 	dpcm->fe = fe;
 	be->dpcm[stream].runtime = fe->dpcm[stream].runtime;
 	dpcm->state = SND_SOC_DPCM_LINK_STATE_NEW;
+
+	pr_info("%s: 5\n", __func__);
+
 	snd_soc_dpcm_stream_lock_irq(fe, stream);
+
+	pr_info("%s: 6\n", __func__);
+
 	list_add(&dpcm->list_be, &fe->dpcm[stream].be_clients);
+
+	pr_info("%s: 7\n", __func__);
+
 	list_add(&dpcm->list_fe, &be->dpcm[stream].fe_clients);
+
+	pr_info("%s: 8\n", __func__);
+
 	snd_soc_dpcm_stream_unlock_irq(fe, stream);
 
+	pr_info("%s: 9\n", __func__);
+
 	dev_dbg(fe->dev, "connected new DPCM %s path %s %s %s\n",
 			stream ? "capture" : "playback",  fe->dai_link->name,
 			stream ? "<-" : "->", be->dai_link->name);
 
+	pr_info("%s: 10\n", __func__);
+
 	dpcm_create_debugfs_state(dpcm, stream);
 
+	pr_info("%s: end\n", __func__);
+
 	return 1;
 }
 

^ permalink raw reply related	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-01 15:47           ` Alex Natalsson
@ 2022-07-01 16:42             ` Pierre-Louis Bossart
  2022-07-01 22:17               ` Alex Natalsson
  0 siblings, 1 reply; 11+ messages in thread
From: Pierre-Louis Bossart @ 2022-07-01 16:42 UTC (permalink / raw)
  To: Alex Natalsson, Kai Vehmanen, Bard Liao, Ranjani Sridharan,
	Takashi Iwai, Mark Brown, linux-sound,
	ALSA Development Mailing List



On 7/1/22 10:47, Alex Natalsson wrote:
>> Wow. So there's already a problem with a config and we made it worse...
>>
>> Can you try with this hack attached, just to see what causes the kernel
>> oops?  Thanks!
> 
> Hello friends)
> I modified your hack a little. And I don't understanding what is fe
> and be, but I see what problem apearing due to be_substream points to
> 0.
> The
> "if (!fe_substream->pcm->nonatomic && be_substream->pcm->nonatomic) {"
> at result gives the error...
> 
> * I am sorry what delete recipients in the previous send((

Interesting, thanks for the test results!

[  108.090732] dpcm_be_connect: start

[  108.090734] dpcm_be_connect: 1

[  108.090735] dpcm_be_connect: 3

[  108.090737] dpcm_be_connect: 3.1

[  108.090738] dpcm_be_connect: 3.1 fe_substream_addr=128378368

[  108.090740] dpcm_be_connect: 3.1 be_substream_addr=0

[  108.090750] Unable to handle kernel NULL pointer dereference at
virtual address 0000000000000000

Indeed it looks like we are de-referencing a NULL pointer, be_substream
isn't initialized.

we could add a simple error check as in the diff below but I don't know
what the root cause might be

	
diff --git a/sound/soc/soc-pcm.c b/sound/soc/soc-pcm.c

index a827cc3c158a..093b98b0e394 100644

--- a/sound/soc/soc-pcm.c

+++ b/sound/soc/soc-pcm.c

@@ -1203,6 +1203,15 @@ static int dpcm_be_connect(struct
snd_soc_pcm_runtime *fe,

        fe_substream = snd_soc_dpcm_get_substream(fe, stream);

        be_substream = snd_soc_dpcm_get_substream(be, stream);



+       if (!fe_substream) {

+               dev_err(fe->dev, "%s: fe_substream not initialized\n",
__func__);

+               return -EINVAL;

+       }

+       if (!be_substream) {

+               dev_err(be->dev, "%s: be_substream not initialized\n",
__func__);

+               return -EINVAL;

+       }

+

        if (!fe_substream->pcm->nonatomic && be_substream->pcm->nonatomic) {

                dev_err(be->dev, "%s: FE is atomic but BE is nonatomic,
invalid configuration\n",

                        __func__);


^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-01 16:42             ` Pierre-Louis Bossart
@ 2022-07-01 22:17               ` Alex Natalsson
  2022-07-09 11:19                 ` Alex Natalsson
  0 siblings, 1 reply; 11+ messages in thread
From: Alex Natalsson @ 2022-07-01 22:17 UTC (permalink / raw)
  To: Pierre-Louis Bossart
  Cc: ALSA Development Mailing List, Kai Vehmanen, Takashi Iwai,
	Ranjani Sridharan, linux-sound, Mark Brown, Bard Liao

> +       if (!fe_substream) {
> +               dev_err(fe->dev, "%s: fe_substream not initialized\n",
> __func__);
> +               return -EINVAL;
> +       }
> +       if (!be_substream) {
> +               dev_err(be->dev, "%s: be_substream not initialized\n",
> __func__);
> +               return -EINVAL;
> +       }
> +

Good) USB-audio is working)
May be and A311D internal sound card probably to repear too?))
Big Thanks!

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-01 22:17               ` Alex Natalsson
@ 2022-07-09 11:19                 ` Alex Natalsson
  2022-07-11 14:33                   ` Pierre-Louis Bossart
  0 siblings, 1 reply; 11+ messages in thread
From: Alex Natalsson @ 2022-07-09 11:19 UTC (permalink / raw)
  To: Pierre-Louis Bossart
  Cc: ALSA Development Mailing List, Kai Vehmanen, Takashi Iwai,
	Ranjani Sridharan, linux-sound, Mark Brown, Bard Liao

> > +       if (!fe_substream) {
> > +               dev_err(fe->dev, "%s: fe_substream not initialized\n",
> > __func__);
> > +               return -EINVAL;
> > +       }
> > +       if (!be_substream) {
> > +               dev_err(be->dev, "%s: be_substream not initialized\n",
> > __func__);
> > +               return -EINVAL;
> > +       }
> > +

Will be this in upstream or needing bugzilla reporting message?

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-09 11:19                 ` Alex Natalsson
@ 2022-07-11 14:33                   ` Pierre-Louis Bossart
  2022-07-12  8:00                     ` Péter Ujfalusi
  2022-07-12  9:29                     ` Takashi Iwai
  0 siblings, 2 replies; 11+ messages in thread
From: Pierre-Louis Bossart @ 2022-07-11 14:33 UTC (permalink / raw)
  To: Alex Natalsson
  Cc: ALSA Development Mailing List, Kai Vehmanen, Takashi Iwai,
	Péter Ujfalusi, Ranjani Sridharan, linux-sound, Mark Brown,
	Bard Liao



On 7/9/22 06:19, Alex Natalsson wrote:
>>> +       if (!fe_substream) {
>>> +               dev_err(fe->dev, "%s: fe_substream not initialized\n",
>>> __func__);
>>> +               return -EINVAL;
>>> +       }
>>> +       if (!be_substream) {
>>> +               dev_err(be->dev, "%s: be_substream not initialized\n",
>>> __func__);
>>> +               return -EINVAL;
>>> +       }
>>> +
> 
> Will be this in upstream or needing bugzilla reporting message?
I created a patch based on this, see
https://github.com/thesofproject/linux/pull/3735

I am not sure however if this is the 'right' fix. There was a comment
from Peter Ujfalusi that a BE substream may be initialized later, but if
that's the case then the atomicity check that was introduced is done in
the wrong location.

Takashi, we could use your guidance here.
Thanks
-Pierre

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-11 14:33                   ` Pierre-Louis Bossart
@ 2022-07-12  8:00                     ` Péter Ujfalusi
  2022-07-12  9:29                     ` Takashi Iwai
  1 sibling, 0 replies; 11+ messages in thread
From: Péter Ujfalusi @ 2022-07-12  8:00 UTC (permalink / raw)
  To: Pierre-Louis Bossart, Alex Natalsson
  Cc: ALSA Development Mailing List, Kai Vehmanen, Takashi Iwai,
	Ranjani Sridharan, linux-sound, Mark Brown, Bard Liao



On 11/07/2022 17:33, Pierre-Louis Bossart wrote:
> 
> 
> On 7/9/22 06:19, Alex Natalsson wrote:
>>>> +       if (!fe_substream) {
>>>> +               dev_err(fe->dev, "%s: fe_substream not initialized\n",
>>>> __func__);
>>>> +               return -EINVAL;
>>>> +       }
>>>> +       if (!be_substream) {
>>>> +               dev_err(be->dev, "%s: be_substream not initialized\n",
>>>> __func__);
>>>> +               return -EINVAL;
>>>> +       }
>>>> +
>>
>> Will be this in upstream or needing bugzilla reporting message?
> I created a patch based on this, see
> https://github.com/thesofproject/linux/pull/3735
> 
> I am not sure however if this is the 'right' fix. There was a comment
> from Peter Ujfalusi that a BE substream may be initialized later, but if
> that's the case then the atomicity check that was introduced is done in
> the wrong location.

fwiw, the dpcm_apply_symmetry() have this check at line 1822:

/* A backend may not have the requested substream */
if (!be_substream)
	continue;

both dpcm_be_connect() and dpcm_apply_symmetry() are called via
dpcm_fe_dai_open() line 2736-2739:

/* calculate valid and active FE <-> BE dpcms */
dpcm_process_paths(fe, stream, &list, 1);

ret = dpcm_fe_dai_startup(fe_substream);

dpcm_fe_dai_open -> dpcm_process_paths -> dpcm_add_paths > dpcm_be_connect

dpcm_fe_dai_open -> dpcm_fe_dai_startup -> dpcm_apply_symmetry

If the check was added by
6246f283d5e02 ("ASoC: dpcm: skip missing substream while applying symmetry")

It looks like that it is not uncommon to not have be_substream at this
point...

> Takashi, we could use your guidance here.
> Thanks
> -Pierre

-- 
Péter

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-11 14:33                   ` Pierre-Louis Bossart
  2022-07-12  8:00                     ` Péter Ujfalusi
@ 2022-07-12  9:29                     ` Takashi Iwai
  2022-07-12 13:05                       ` Alex Natalsson
  1 sibling, 1 reply; 11+ messages in thread
From: Takashi Iwai @ 2022-07-12  9:29 UTC (permalink / raw)
  To: Pierre-Louis Bossart
  Cc: ALSA Development Mailing List, Alex Natalsson, Kai Vehmanen,
	Péter Ujfalusi, Ranjani Sridharan, linux-sound, Mark Brown,
	Bard Liao

On Mon, 11 Jul 2022 16:33:14 +0200,
Pierre-Louis Bossart wrote:
> 
> 
> 
> On 7/9/22 06:19, Alex Natalsson wrote:
> >>> +       if (!fe_substream) {
> >>> +               dev_err(fe->dev, "%s: fe_substream not initialized\n",
> >>> __func__);
> >>> +               return -EINVAL;
> >>> +       }
> >>> +       if (!be_substream) {
> >>> +               dev_err(be->dev, "%s: be_substream not initialized\n",
> >>> __func__);
> >>> +               return -EINVAL;
> >>> +       }
> >>> +
> > 
> > Will be this in upstream or needing bugzilla reporting message?
> I created a patch based on this, see
> https://github.com/thesofproject/linux/pull/3735
> 
> I am not sure however if this is the 'right' fix. There was a comment
> from Peter Ujfalusi that a BE substream may be initialized later, but if
> that's the case then the atomicity check that was introduced is done in
> the wrong location.
> 
> Takashi, we could use your guidance here.

I guess that the check of fe_substream there is superfluous.  It must
be never NULL.  And, in principle, passing the invalid BE to this
function itself sounds already wrong.  That said, if any check is
needed, it should be done beforehand at choosing the right BE.

A fix like below might work instead?  (Totally untested)


thanks,

Takashi

-- 8< --
--- a/sound/soc/soc-pcm.c
+++ b/sound/soc/soc-pcm.c
@@ -1318,6 +1318,9 @@ static struct snd_soc_pcm_runtime *dpcm_get_be(struct snd_soc_card *card,
 		if (!be->dai_link->no_pcm)
 			continue;
 
+		if (!snd_soc_dpcm_get_substream(be, stream))
+			continue;
+
 		for_each_rtd_dais(be, i, dai) {
 			w = snd_soc_dai_get_widget(dai, stream);
 

^ permalink raw reply	[flat|nested] 11+ messages in thread

* Re: Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device
  2022-07-12  9:29                     ` Takashi Iwai
@ 2022-07-12 13:05                       ` Alex Natalsson
  0 siblings, 0 replies; 11+ messages in thread
From: Alex Natalsson @ 2022-07-12 13:05 UTC (permalink / raw)
  To: Takashi Iwai
  Cc: ALSA Development Mailing List, Kai Vehmanen, Péter Ujfalusi,
	Pierre-Louis Bossart, linux-sound, Ranjani Sridharan, Mark Brown,
	Bard Liao

> +               if (!snd_soc_dpcm_get_substream(be, stream))
> +                       continue;
> +
And this good works for me too and kernel not oopsing)

^ permalink raw reply	[flat|nested] 11+ messages in thread

end of thread, other threads:[~2022-07-12 13:06 UTC | newest]

Thread overview: 11+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
     [not found] <CADs9LoPZH_D+eJ9qjTxSLE5jGyhKsjMN7g2NighZ16biVxsyKw@mail.gmail.com>
2022-06-27 15:12 ` Sound not working after commit bbf7d3b1c4f40eb02dd1dffb500ba00b0bff0303 on Amlogic A311D device Pierre-Louis Bossart
2022-06-27 16:52   ` Mark Brown
     [not found]   ` <CADs9LoN-L0X1Dr1sP2K7xrcWm7dpHW6MhF47c2eBB0moLNnPRQ@mail.gmail.com>
2022-06-28 22:47     ` Pierre-Louis Bossart
     [not found]       ` <CADs9LoOZjK=cUuNSEELtgxYoA+yHVFKM_Y9YLcY74smqx8XsjQ@mail.gmail.com>
     [not found]         ` <c554b69c-0c73-158d-85d8-95a0375babeb@linux.intel.com>
2022-07-01 15:47           ` Alex Natalsson
2022-07-01 16:42             ` Pierre-Louis Bossart
2022-07-01 22:17               ` Alex Natalsson
2022-07-09 11:19                 ` Alex Natalsson
2022-07-11 14:33                   ` Pierre-Louis Bossart
2022-07-12  8:00                     ` Péter Ujfalusi
2022-07-12  9:29                     ` Takashi Iwai
2022-07-12 13:05                       ` Alex Natalsson

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox