Failed to get suitable pool for 38200000.usb
This morning the display would not turn on on my L5.
At the very end of the log I saw:
Jul 28 09:40:18 nn kernel: cpu cpu0: _set_opp_voltage: failed to set voltage (1000000 1000000 1000000 mV): -11
Jul 28 09:40:19 nn kernel: cpufreq: __target_index: Failed to change cpu frequency: -11
that seems to be a result of other problems before that. The first problem hit in the middle of the night (at that point in time the phone was blanked and idle):
Jul 28 02:49:23 nn kernel: ------------[ cut here ]------------
Jul 28 02:49:35 nn kernel: Failed to get suitable pool for 38200000.usb
Jul 28 02:49:35 nn kernel: WARNING: CPU: 0 PID: 0 at kernel/dma/pool.c:279 dma_alloc_from_pool+0x94/0x1ec
Jul 28 02:49:35 nn kernel: Modules linked in: hid_multitouch usbhid aes_ce_ccm rfcomm algif_hash algif_skcipher af_alg bnep redpine_sdio redpine_91x bluetooth mac80211 cfg80211 usb_f_acm u_serial usb_f_ecm u_ether qmi_wwan cdc_wdm caam_jr usbnet option mii caamhash_desc >
Jul 28 02:49:40 nn kernel: ledtrig_timer ledtrig_pattern libcrc32c nfnetlink fuse zram ip_tables x_tables ipv6 uas usb_storage xhci_plat_hcd ofpart xhci_hcd spi_nor mtd usbcore aes_ce_blk crct10dif_ce cdns_mhdp_imx ghash_ce sha2_ce sha1_ce pwm_vibra dwc3 cdns_mhdp_drmco>
Jul 28 02:49:40 nn kernel: CPU: 0 PID: 0 Comm: swapper/0 Tainted: G C 5.18.0-1-librem5 #1
Jul 28 02:49:40 nn kernel: Hardware name: Purism Librem 5r4 (DT)
Jul 28 02:49:40 nn kernel: pstate: 600000c5 (nZCv daIF -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
Jul 28 02:49:40 nn kernel: pc : dma_alloc_from_pool+0x94/0x1ec
Jul 28 02:49:40 nn kernel: lr : dma_alloc_from_pool+0x94/0x1ec
Jul 28 02:49:40 nn kernel: sp : ffff8000095133b0
Jul 28 02:49:40 nn kernel: x29: ffff8000095133b0 x28: ffff8000097feb68 x27: 0000000000000000
Jul 28 02:49:40 nn kernel: x26: ffff00000038bb80 x25: ffff800009513470 x24: ffff0000009bf010
Jul 28 02:49:40 nn kernel: x23: ffff8000097feb48 x22: ffff8000080deac0 x21: ffff800009029b70
Jul 28 02:49:40 nn kernel: x20: 0000000000001000 x19: ffff00002d19e818 x18: 0000000000000000
Jul 28 02:49:40 nn kernel: x17: ffff8000b2952000 x16: ffff800008004000 x15: 0000000000000030
Jul 28 02:49:40 nn kernel: x14: 0000000000000000 x13: 205d353736303836 x12: 2e3732393132315b
Jul 28 02:49:40 nn kernel: x11: 00000000ffffdfff x10: 00000000ffffdfff x9 : ffff8000080b4f6c
Jul 28 02:49:40 nn kernel: x8 : 000000000002ffe8 x7 : c0000000ffffdfff x6 : 0000000000017ff4
Jul 28 02:49:40 nn kernel: x5 : ffff0000bbb02990 x4 : 0000000000000000 x3 : 0000000000000027
Jul 28 02:49:40 nn kernel: x2 : 0000000000000000 x1 : 0000000000000000 x0 : ffff800009521200
Jul 28 02:49:40 nn kernel: Call trace:
Jul 28 02:49:40 nn kernel: dma_alloc_from_pool+0x94/0x1ec
Jul 28 02:49:40 nn kernel: dma_direct_alloc+0x98/0x320
Jul 28 02:49:40 nn kernel: dma_alloc_attrs+0x88/0xf0
Jul 28 02:49:40 nn kernel: dma_pool_alloc+0x120/0x1fc
Jul 28 02:49:40 nn kernel: xhci_segment_alloc+0x90/0x150 [xhci_hcd]
Jul 28 02:49:40 nn kernel: xhci_alloc_segments_for_ring+0xe4/0x1d0 [xhci_hcd]
Jul 28 02:49:40 nn kernel: xhci_ring_expansion+0x78/0x380 [xhci_hcd]
Jul 28 02:49:40 nn kernel: prepare_ring+0x1b0/0x2c0 [xhci_hcd]
Jul 28 02:49:40 nn kernel: prepare_transfer+0x88/0x170 [xhci_hcd]
Jul 28 02:49:40 nn kernel: xhci_queue_bulk_tx+0xd8/0x910 [xhci_hcd]
Jul 28 02:49:40 nn kernel: xhci_urb_enqueue+0x340/0x590 [xhci_hcd]
Jul 28 02:49:40 nn kernel: usb_hcd_submit_urb+0xd4/0xa54 [usbcore]
Jul 28 02:49:40 nn kernel: usb_submit_urb+0x1f8/0x830 [usbcore]
Jul 28 02:49:40 nn kernel: rx_submit+0x178/0x30c [usbnet]
Jul 28 02:49:40 nn kernel: rx_complete+0x2b0/0x310 [usbnet]
Jul 28 02:49:40 nn kernel: __usb_hcd_giveback_urb+0xa4/0x154 [usbcore]
Jul 28 02:49:40 nn kernel: usb_giveback_urb_bh+0xb4/0x11c [usbcore]
Jul 28 02:49:40 nn kernel: tasklet_action_common.constprop.0+0xf8/0x120
Jul 28 02:49:40 nn kernel: tasklet_action+0x34/0x40
Jul 28 02:49:40 nn kernel: __do_softirq+0x120/0x3b8
Jul 28 02:49:40 nn kernel: __irq_exit_rcu+0xac/0xf0
Jul 28 02:49:40 nn kernel: irq_exit_rcu+0x1c/0x30
Jul 28 02:49:40 nn kernel: el1_interrupt+0x38/0x70
Jul 28 02:49:40 nn kernel: el1h_64_irq_handler+0x18/0x24
Jul 28 02:49:40 nn kernel: el1h_64_irq+0x64/0x68
Jul 28 02:49:40 nn kernel: cpuidle_enter_state+0xc4/0x3d0
Jul 28 02:49:40 nn kernel: cpuidle_enter+0x44/0x5c
Jul 28 02:49:40 nn kernel: do_idle+0x218/0x2a0
Jul 28 02:49:40 nn kernel: cpu_startup_entry+0x34/0x40
Jul 28 02:49:40 nn kernel: rest_init+0xfc/0x100
Jul 28 02:49:40 nn kernel: arch_post_acpi_subsys_init+0x0/0x30
Jul 28 02:49:40 nn kernel: start_kernel+0x6d0/0x710
Jul 28 02:49:40 nn kernel: __primary_switched+0xc0/0xc8
Jul 28 02:49:40 nn kernel: ---[ end trace 0000000000000000 ]---
Jul 28 02:49:40 nn kernel: xhci-hcd xhci-hcd.4.auto: Ring expansion failed
Jul 28 02:49:40 nn kernel: ------------[ cut here ]------------
(many more of these follow)
up til 03:08:33, from then on it's "only"
Jul 28 03:08:33 nn kernel: cpu cpu0: _set_opp_voltage: failed to set voltage (1000000 1000000 1000000 mV): -11
Jul 28 03:08:33 nn kernel: cpufreq: __target_index: Failed to change cpu frequency: -11
This is 5.18.0-1-librem5 #1 SMP PREEMPT Fri Jul 15 15:27:41 UTC 2022
.
I labeled this as "crash" altough one can still ssh into the system as it looks crash from a users point of view.