Rpi3b+ - OSMC 06-2023 - WiFi crash kernel after ~6h video playing

Help and Support Raspberry Pi
1

Hello,

It seems that 06/2023 update isn’t very friendly with Rpi3b+ & his embedded WiFi card.
It seems to be a conflict between WiFi driver & 5.15.83-3 Kernel.

So after playing over 5-6h of video (cumulated, with no reboot), all the network stack crash.
I suppose that it can be faster with an iperf test, but I didn’t test yet.

I can exclude some possible causes like :
Problem between 2.4Ghz & 5Ghz => Tested on 2.4Ghz & 5Ghz with 2 differents SSID
WiFi country parameter (crda package deployed & configured with my WiFi region => No effect)
Material problem => tested on 2 two differents Rpi 3b+ => LAN no problem => WiFi crash

What hasn’t be tested => Make a fresh install (not upgraded from previous versions) & show what happens.

And to conclude, here is the kernel trace:

Jun 29 14:47:48 osmc kernel: [69143.488511] kworker/u9:0: page allocation failure: order:0, mode:0x800(GFP_NOWAIT), nodemask=(null),cpuset=/,mems_allowed=0
Jun 29 14:47:48 osmc kernel: [69143.488592] CPU: 0 PID: 67 Comm: kworker/u9:0 Tainted: G C 5.15.83-3-osmc #1
Jun 29 14:47:48 osmc kernel: [69143.488603] Hardware name: BCM2835
Jun 29 14:47:48 osmc kernel: [69143.488611] Workqueue: brcmf_wq/mmc1:0001:1 brcmf_sdio_dataworker [brcmfmac]
Jun 29 14:47:48 osmc kernel: [69143.488886] Backtrace:
Jun 29 14:47:48 osmc kernel: [69143.488895] [<80bdf758>] (dump_backtrace) from [<80bdf9a0>] (show_stack+0x20/0x24)
Jun 29 14:47:48 osmc kernel: [69143.488928] r7:ffffe000 r6:00000000 r5:80e06f74 r4:680f0013
Jun 29 14:47:48 osmc kernel: [69143.488933] [<80bdf980>] (show_stack) from [<80be5014>] (dump_stack_lvl+0x70/0x94)
Jun 29 14:47:48 osmc kernel: [69143.488953] [<80be4fa4>] (dump_stack_lvl) from [<80be5050>] (dump_stack+0x18/0x1c)
Jun 29 14:47:48 osmc kernel: [69143.488970] r7:ffffe000 r6:00000000 r5:80e1b60c r4:00000000
Jun 29 14:47:48 osmc kernel: [69143.488975] [<80be5038>] (dump_stack) from [<803876ec>] (warn_alloc+0x110/0x1b4)
Jun 29 14:47:48 osmc kernel: [69143.488993] [<803875dc>] (warn_alloc) from [<80389028>] (__alloc_pages+0x1898/0x194c)
Jun 29 14:47:48 osmc kernel: [69143.489011] r3:00000000 r2:80e1b60c
Jun 29 14:47:48 osmc kernel: [69143.489019] r7:00000081 r6:00000000 r5:00000800 r4:00000000
Jun 29 14:47:48 osmc kernel: [69143.489023] [<80387790>] (__alloc_pages) from [<8039db40>] (new_slab+0x4cc/0x54c)
Jun 29 14:47:48 osmc kernel: [69143.489055] r10:812057c8 r9:00000000 r8:ffffffff r7:00000020 r6:00000000 r5:81401240
Jun 29 14:47:48 osmc kernel: [69143.489062] r4:00000800
Jun 29 14:47:48 osmc kernel: [69143.489068] [<8039d674>] (new_slab) from [<803a08f0>] (___slab_alloc.constprop.0+0x4d4/0x96c)
Jun 29 14:47:48 osmc kernel: [69143.489092] r9:baa56cc0 r8:00000000 r7:81205814 r6:00000000 r5:81401240 r4:81401240
Jun 29 14:47:48 osmc kernel: [69143.489097] [<803a041c>] (___slab_alloc.constprop.0) from [<803a2e78>] (__kmalloc+0x52c/0x5d0)
Jun 29 14:47:48 osmc kernel: [69143.489119] r10:01ad2b90 r9:00000900 r8:00000000 r7:81205814 r6:00000000 r5:81401240
Jun 29 14:47:48 osmc kernel: [69143.489125] r4:81125cc0
Jun 29 14:47:48 osmc kernel: [69143.489129] [<803a294c>] (__kmalloc) from [<80807fd8>] (bcm2835_dma_create_cb_chain+0x5c/0x3a4)
Jun 29 14:47:48 osmc kernel: [69143.489155] r10:40000000 r9:00000000 r8:7e300020 r7:00000000 r6:8174ac40 r5:00000002
Jun 29 14:47:48 osmc kernel: [69143.489161] r4:00000001
Jun 29 14:47:48 osmc kernel: [69143.489165] [<80807f7c>] (bcm2835_dma_create_cb_chain) from [<80808888>] (bcm2835_dma_prep_slave_sg+0x148/0x3bc)
Jun 29 14:47:48 osmc kernel: [69143.489185] r10:40000000 r9:00000000 r8:00000000 r7:00000001 r6:8174ac40 r5:81c19c50
Jun 29 14:47:48 osmc kernel: [69143.489191] r4:00000001
Jun 29 14:47:48 osmc kernel: [69143.489195] [<80808740>] (bcm2835_dma_prep_slave_sg) from [<809b7a04>] (bcm2835_mmc_transfer_dma+0xf0/0x23c)
Jun 29 14:47:48 osmc kernel: [69143.489217] r10:b097c400 r9:80808740 r8:00000001 r7:00000002 r6:00000000 r5:8174ac40
Jun 29 14:47:48 osmc kernel: [69143.489223] r4:81429b40
Jun 29 14:47:48 osmc kernel: [69143.489227] [<809b7914>] (bcm2835_mmc_transfer_dma) from [<809b8414>] (bcm2835_mmc_request+0xa8/0xc8)
Jun 29 14:47:48 osmc kernel: [69143.489246] r9:00000000 r8:00000003 r7:680f0013 r6:81429b40 r5:81c19c98 r4:81429800
Jun 29 14:47:48 osmc kernel: [69143.489251] [<809b836c>] (bcm2835_mmc_request) from [<80996ad0>] (__mmc_start_request+0x88/0x1c4)
Jun 29 14:47:48 osmc kernel: [69143.489270] r7:81c19ca8 r6:81c19c98 r5:81c19c98 r4:81429800
Jun 29 14:47:48 osmc kernel: [69143.489275] [<80996a48>] (__mmc_start_request) from [<80996ca0>] (mmc_start_request+0x94/0xbc)
Jun 29 14:47:48 osmc kernel: [69143.489293] r7:81c19ca8 r6:00000000 r5:81c19c98 r4:81429800
Jun 29 14:47:48 osmc kernel: [69143.489297] [<80996c0c>] (mmc_start_request) from [<80996de0>] (mmc_wait_for_req+0x78/0x150)
Jun 29 14:47:48 osmc kernel: [69143.489315] r7:81c19ca8 r6:8142a800 r5:81c19c98 r4:81429800
Jun 29 14:47:48 osmc kernel: [69143.489320] [<80996d68>] (mmc_wait_for_req) from [<809a55e4>] (mmc_io_rw_extended+0x22c/0x338)
Jun 29 14:47:48 osmc kernel: [69143.489339] r7:00000001 r6:8142a800 r5:00080000 r4:00000600
Jun 29 14:47:48 osmc kernel: [69143.489344] [<809a53b8>] (mmc_io_rw_extended) from [<809a74c4>] (sdio_io_rw_ext_helper+0x1e8/0x338)
Jun 29 14:47:48 osmc kernel: [69143.489364] r10:00000000 r9:00008000 r8:b097ca00 r7:81e6e400 r6:8142a800 r5:00000000
Jun 29 14:47:48 osmc kernel: [69143.489370] r4:000001ff
Jun 29 14:47:48 osmc kernel: [69143.489375] [<809a72dc>] (sdio_io_rw_ext_helper) from [<809a7644>] (sdio_readsb+0x30/0x38)
Jun 29 14:47:48 osmc kernel: [69143.489395] r10:7f36e1c4 r9:18000000 r8:b80720c0 r7:18000000 r6:84f96c00 r5:84f96c00
Jun 29 14:47:48 osmc kernel: [69143.489401] r4:b80720c0
Jun 29 14:47:48 osmc kernel: [69143.489405] [<809a7614>] (sdio_readsb) from [<7f358834>] (brcmf_sdiod_skbuff_read+0xd4/0xec [brcmfmac])
Jun 29 14:47:48 osmc kernel: [69143.489605] [<7f358760>] (brcmf_sdiod_skbuff_read [brcmfmac]) from [<7f359944>] (brcmf_sdiod_recv_pkt+0xcc/0xf8 [brcmfmac])
Jun 29 14:47:48 osmc kernel: [69143.489982] r5:b097c400 r4:00000000
Jun 29 14:47:48 osmc kernel: [69143.489986] [<7f359878>] (brcmf_sdiod_recv_pkt [brcmfmac]) from [<7f3550c0>] (brcmf_sdio_dataworker+0x1030/0x2884 [brcmfmac])
Jun 29 14:47:48 osmc kernel: [69143.490368] r9:84f97268 r8:00000200 r7:00000004 r6:b097ca00 r5:b097c400 r4:b80720c0
Jun 29 14:47:48 osmc kernel: [69143.490374] [<7f354090>] (brcmf_sdio_dataworker [brcmfmac]) from [<8014b988>] (process_one_work+0x214/0x5a8)
Jun 29 14:47:48 osmc kernel: [69143.490579] r10:81434605 r9:00000120 r8:000a001a r7:81434600 r6:81766e00 r5:81d2e200
Jun 29 14:47:48 osmc kernel: [69143.490585] r4:84f97268
Jun 29 14:47:48 osmc kernel: [69143.490590] [<8014b774>] (process_one_work) from [<8014bd80>] (worker_thread+0x64/0x5fc)
Jun 29 14:47:48 osmc kernel: [69143.490610] r10:81766e00 r9:00000088 r8:81203d00 r7:81766e18 r6:81d2e218 r5:81766e00
Jun 29 14:47:48 osmc kernel: [69143.490616] r4:81d2e200
Jun 29 14:47:48 osmc kernel: [69143.490620] [<8014bd1c>] (worker_thread) from [<8015575c>] (kthread+0x16c/0x1a0)
Jun 29 14:47:48 osmc kernel: [69143.490643] r10:81697260 r9:8151bcfc r8:81c18000 r7:81d2e200 r6:8014bd1c r5:81697240
Jun 29 14:47:48 osmc kernel: [69143.490650] r4:81d28780
Jun 29 14:47:48 osmc kernel: [69143.490654] [<801555f0>] (kthread) from [<801000d4>] (ret_from_fork+0x14/0x20)
Jun 29 14:47:48 osmc kernel: [69143.490672] Exception stack(0x81c19fb0 to 0x81c19ff8)
Jun 29 14:47:48 osmc kernel: [69143.490681] 9fa0: 00000000 00000000 00000000 00000000
Jun 29 14:47:48 osmc kernel: [69143.490692] 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Jun 29 14:47:48 osmc kernel: [69143.490701] 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000
Jun 29 14:47:48 osmc kernel: [69143.490712] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:801555f0
Jun 29 14:47:48 osmc kernel: [69143.490719] r4:81d28780 r3:00000000
Jun 29 14:47:48 osmc kernel: [69143.490795] Mem-Info:
Jun 29 14:47:48 osmc kernel: [69143.490809] active_anon:181 inactive_anon:53060 isolated_anon:0
Jun 29 14:47:48 osmc kernel: [69143.490809] active_file:3494 inactive_file:2010 isolated_file:0
Jun 29 14:47:48 osmc kernel: [69143.490809] unevictable:352 dirty:2 writeback:0
Jun 29 14:47:48 osmc kernel: [69143.490809] slab_reclaimable:3202 slab_unreclaimable:71327
Jun 29 14:47:48 osmc kernel: [69143.490809] mapped:4640 shmem:176 pagetables:653 bounce:0
Jun 29 14:47:48 osmc kernel: [69143.490809] kernel_misc_reclaimable:0
Jun 29 14:47:48 osmc kernel: [69143.490809] free:42175 free_pcp:0 free_cma:36604
Jun 29 14:47:48 osmc kernel: [69143.490835] Node 0 active_anon:724kB inactive_anon:212240kB active_file:13976kB inactive_file:8040kB unevictable:1408kB isolated(anon):0kB isolated(file):0kB mapped:18560kB dirty:8kB writeback:0kB shmem:704kB writeback_tmp:0kB kernel_stack:1592kB pagetables:2612kB all_unreclaimable? no
Jun 29 14:47:48 osmc kernel: [69143.490858] DMA free:168700kB min:12288kB low:15360kB high:18432kB reserved_highatomic:0KB active_anon:724kB inactive_anon:212240kB active_file:13952kB inactive_file:7912kB unevictable:1408kB writepending:8kB present:970752kB managed:943756kB mlocked:1408kB bounce:0kB free_pcp:4kB local_pcp:0kB free_cma:146416kB
Jun 29 14:47:48 osmc kernel: [69143.490883] lowmem_reserve: 0 0 0
Jun 29 14:47:48 osmc kernel: [69143.490915] DMA: 28094kB (UMEC) 14328kB (UMEC) 67516kB (UMEC) 71432kB (UMEC) 32064kB (UMEC) 128128kB (MC) 66256kB (C) 14512kB (C) 71024kB (C) 22048kB (C) 10*4096kB (C) = 169492kB
Jun 29 14:47:48 osmc kernel: [69143.491098] 5970 total pagecache pages
Jun 29 14:47:48 osmc kernel: [69143.491105] 0 pages in swap cache
Jun 29 14:47:48 osmc kernel: [69143.491113] Swap cache stats: add 0, delete 0, find 0/0
Jun 29 14:47:48 osmc kernel: [69143.491122] Free swap = 0kB
Jun 29 14:47:48 osmc kernel: [69143.491129] Total swap = 0kB
Jun 29 14:47:48 osmc kernel: [69143.491136] 242688 pages RAM
Jun 29 14:47:48 osmc kernel: [69143.491143] 0 pages HighMem/MovableOnly
Jun 29 14:47:48 osmc kernel: [69143.491150] 6749 pages reserved
Jun 29 14:47:48 osmc kernel: [69143.491158] 131072 pages cma reserved
Jun 29 14:47:48 osmc kernel: [69143.491177] SLUB: Unable to allocate memory on node -1, gfp=0x900(GFP_NOWAIT|__GFP_ZERO)
Jun 29 14:47:48 osmc kernel: [69143.491193] cache: kmalloc-128, object size: 128, buffer size: 128, default order: 0, min order: 0
Jun 29 14:47:48 osmc kernel: [69143.491206] node 0: slabs: 44023, objs: 1408736, free: 0
Jun 29 14:47:59 osmc kernel: [69153.938805] mmc1: Timeout waiting for hardware interrupt.
Jun 29 14:50:27 osmc kernel: [69302.419317] INFO: task kworker/u9:0:67 blocked for more than 122 seconds.

Regards,

2

Hi

It’s impossible to tell without full logs.

Sam

3

Hi Sam,

Thanks for your answer.
No problem, but could you tell me what kind of logs do you need please?
The kern.log seems to be the only one which contains something useful.

So, do you want me to change log level of something for example?

Thanks :slight_smile:

4

To get a better understanding of the problem you are experiencing we need more information from you. The best way to get this information is for you to upload logs that demonstrate your problem. You can learn more about how to submit a useful support request here.

Depending on the used skin you have to set the settings-level to standard or higher, in summary:

  • enable debug logging at settings->system->logging

  • reboot the OSMC device twice(!)

  • reproduce the issue

  • upload the log set (all configs and logs!) either using the Log Uploader method within the My OSMC menu in the GUI or the ssh method invoking command grab-logs -A

  • publish the provided URL from the log set upload, here

Thanks for your understanding. We hope that we can help you get up and running again shortly.

OSMC skin screenshot:

image
image960×539 41.4 KB

5

Hello,

Thanks for your help. This is enabled now.
So I believe that it could be ready tomorrow.

Regards,

6

Hello,

You’ll find the grab logs here : https://paste.osmc.tv/yahipugure

I’m ready to test what you want :slight_smile:

Regards

7

Hi,

It seems that the problem is directly linked to the last kernel version.
A kernel downgrade seems to be efficient.

OSMC's June update is here with Kodi v20 - OSMC - #102 by Dey_Dey

Have a good day

1 Like