嵌入式开发者社区

标题: 启动失败的问题 [打印本页]

作者: beying 时间: 2019-8-2 15:42
标题: 启动失败的问题
自己写了arm和2个dsp程序，前几次load-firmware.sh都没问题，多次load-firmware.sh之后，发现串口打印不停，截取部分打印信息如下：
** 8969 printk messages dropped ** [ 13.280074] [<c022d9a4>] (__warn) from [<c022dae8>] (warn_slowpath_fmt+0x40/0x48)
** 6999 printk messages dropped ** [ 13.313016] Modules linked in: pru_rproc pruss_intc pruss snd_soc_simple_card snd_soc_simple_card_utils snd_soc_omap_hdmi_audio pvrsrvkm(O) m25p80 pruss_soc_bus c_can_platform c_can can_dev omap_wdt ahci_platform libahci_platform libahci libata ti_vpe ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 10500 printk messages dropped ** [ 13.362226] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 2712 printk messages dropped ** [ 13.374846] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 4813 printk messages dropped ** [ 13.398074] ---[ end trace 0000000000009736 ]---
** 1651 printk messages dropped ** [ 13.405874] ---[ end trace 0000000000009771 ]---
** 1653 printk messages dropped ** [ 13.413571] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 2634 printk messages dropped ** [ 13.425849] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 2707 printk messages dropped ** [ 13.438433]  r4:00000000
** 1200 printk messages dropped ** [ 13.444026]  r7:00000001 r6:00000000 r5:d064c000 r4:d063f424
** 1871 printk messages dropped ** [ 13.452742] [<c0503ac0>] (l3_interrupt_handler) from [<c02827b8>] (irq_forced_thread_fn+0x28/0x7c)
** 7322 printk messages dropped ** [ 13.486797] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 2703 printk messages dropped ** [ 13.499440] [<c0282790>] (irq_forced_thread_fn) from [<c0282b1c>] (irq_thread+0x124/0x1f8)
** 2438 printk messages dropped ** [ 13.510816]  r10:00000000 r9:00000000 r8:c02829f8 r7:d063f400 r6:d064c000 r5:d063f440
** 2339 printk messages dropped ** [ 13.521694]  r7:00000009 r6:c0bd28c8 r5:00000000 r4:d064de20
** 2746 printk messages dropped ** [ 13.534480] [<c022daac>] (warn_slowpath_fmt) from [<c0503d1c>] (l3_interrupt_handler+0x25c/0x36c)
** 2593 printk messages dropped ** [ 13.546622] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 2710 printk messages dropped ** [ 13.559162] ---[ end trace 0000000000009c09 ]---
** 1651 printk messages dropped ** [ 13.566832] ---[ end trace 0000000000009c44 ]---
** 1653 printk messages dropped ** [ 13.574528] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 2642 printk messages dropped ** [ 13.586813] [<c022d9a4>] (__warn) from [<c022dae8>] (warn_slowpath_fmt+0x40/0x48)
** 2268 printk messages dropped ** [ 13.597372]  r9:0000001b r8:d063f090 r7:c0bd2bf8 r6:00000002 r5:c0bd27f4 r4:c0bd2898
** 4050 printk messages dropped ** [ 13.616296] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 2712 printk messages dropped ** [ 13.628957] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 2671 printk messages dropped ** [ 13.641368]  r9:0000001b r8:d063f090 r7:c0bd2bf8 r6:00000002 r5:c0bd27f4 r4:c0bd2898
** 2341 printk messages dropped ** [ 13.652238] Modules linked in: pru_rproc pruss_intc pruss snd_soc_simple_card snd_soc_simple_card_utils snd_soc_omap_hdmi_audio pvrsrvkm(O) m25p80 pruss_soc_bus c_can_platform c_can can_dev omap_wdt ahci_platform libahci_platform libahci libata ti_vpe ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)

第一次重启板子，从串口观察，卡在下面的地方：
[ 8.616026] hub 3-0:1.0: USB hub found
[ 8.620373] hub 3-0:1.0: 1 port detected
[  OK  ] Started System Logging Service.
[ 8.637550] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 8.643198] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 4
[ 8.654371] spi2.0 supply vcc not found, using dummy regulator
[ 8.661804] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 8.670094] remoteproc remoteproc3: powering up 41000000.dsp
[ 8.670101] remoteproc remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 4520256
[ 8.676900] omap-iommu 41501000.mmu: 41501000.mmu: version 3.0
[ 8.676937] omap-iommu 41502000.mmu: 41502000.mmu: version 3.0

再次重启板子，又循环不停的打印，截取部分打印信息如下：
[ 8.616026] hub 3-0:1.0: USB hub found
[ 8.620373] hub 3-0:1.0: 1 port detected
[  OK  ] Started System Logging Service.
[ 8.637550] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 8.643198] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 4
[ 8.654371] spi2.0 supply vcc not found, using dummy regulator
[ 8.661804] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 8.670094] remoteproc remoteproc3: powering up 41000000.dsp
[ 8.670101] remoteproc remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 4520256
[ 8.676900] omap-iommu 41501000.mmu: 41501000.mmu: version 3.0
[ 8.676937] omap-iommu 41502000.mmu: 41502000.mmu: version 3.0

现在板子起不来了，请问：
1、多load-firmware.sh几次，系统就出现这种问题，这是bug吗？要怎么定位为什么会出现这种问题，arm程序和server_dsp1.xe66、server_dsp2.xe66我都保留了，需要的话，我可以提供给你们。
2、现在板子起不来了（一直从TF卡启动），要重做TF卡吗？（就从TF卡启动，emmc搞坏了估计更麻烦）。

作者: beying 时间: 2019-8-2 15:46
再次重启我的截取有误，更正如下：
[  OK  ] Started udev Coldplug all Devices.
[ 7.611167] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[ 7.619024] remoteproc remoteproc0: 58820000.ipu is available
[ 7.644224] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[ 7.651115] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[ 7.651122] remoteproc remoteproc0: powering up 58820000.ipu
[ 7.651146] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[ 7.651151] remoteproc remoteproc0: request_firmware failed: -2
[  OK  ] Started Load/Save Random Seed.
[ 7.697324] remoteproc remoteproc1: 55020000.ipu is available
[ 7.704821] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[ 7.713965] remoteproc remoteproc1: powering up 55020000.ipu
[ 7.719695] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[ 7.728802] remoteproc remoteproc1: request_firmware failed: -2
[ 7.735307] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[ 7.747865] remoteproc remoteproc2: 40800000.dsp is available
[ 7.755424] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[ 7.767410] remoteproc remoteproc3: 41000000.dsp is available
[ 7.774681] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 7.783970] remoteproc remoteproc2: powering up 40800000.dsp
[ 7.789726] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 7.798827] remoteproc remoteproc2: request_firmware failed: -2
[  OK  ] Started Create Volatile Files and Directories.
      Starting Network Time Synchronization...
      Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Found device /dev/ttyS2.
[ 8.300309] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc2
[ 8.312562] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659 as rtc1
[ 8.383443] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 8.388982] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 3
[ 8.424281] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[ 8.435914] xhci-hcd xhci-hcd.2.auto: irq 483, io mem 0x488d0000
[ 8.442155] remoteproc remoteproc3: powering up 41000000.dsp
[ 8.442164] remoteproc remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 4520256
[ 8.449322] omap-iommu 41501000.mmu: 41501000.mmu: version 3.0
[ 8.449358] omap-iommu 41502000.mmu: 41502000.mmu: version 3.0
** 1384 printk messages dropped ** [ 8.528918] Hardware name: Generic DRA74X (Flattened Device Tree)
** 2210 printk messages dropped ** [ 8.538082] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 3049 printk messages dropped ** [ 8.550722] 44000000.ocp

3 Custom Error: MASTER DSP2_DMA TARGET L4_PER3_P3 (Read): Data Access in User mode during Functional access
** 3669 printk messages dropped ** [ 8.565822] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 3051 printk messages dropped ** [ 8.578365] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 3046 printk messages dropped ** [ 8.590927] ---[ end trace 000000000000024b ]---
** 1861 printk messages dropped ** [ 8.598615]  r9:0000001b r8:d063f090 r7:c0bd2bf8 r6:00000002 r5:c0bd27f4 r4:c0bd2898
** 2623 printk messages dropped ** [ 8.609425] Hardware name: Generic DRA74X (Flattened Device Tree)
** 2220 printk messages dropped ** [ 8.618590] [<c022daac>] (warn_slowpath_fmt) from [<c0503d1c>] (l3_interrupt_handler+0x25c/0x36c)
** 2901 printk messages dropped ** [ 8.630557] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 3050 printk messages dropped ** [ 8.643140] Modules linked in: ti_vpe(+) ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 12880 printk messages dropped ** [ 8.696136] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 7849 printk messages dropped ** [ 8.727530] [<c022daac>] (warn_slowpath_fmt) from [<c0503d1c>] (l3_interrupt_handler+0x25c/0x36c)
** 15893 printk messages dropped ** [ 8.793638] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 13408 printk messages dropped ** [ 8.848598] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 13145 printk messages dropped ** [ 8.903030]  r3:d0619ec0 r2:c0bd2898
** 11722 printk messages dropped ** [ 8.952099] Hardware name: Generic DRA74X (Flattened Device Tree)
** 4981 printk messages dropped ** [ 8.973385] Modules linked in: ahci_platform(+) libahci_platform libahci libata ti_vpe(+) ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 20358 printk messages dropped ** [ 9.060290] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 7579 printk messages dropped ** [ 9.093767]  r4:d063f400
** 7519 printk messages dropped ** [ 9.127229] Backtrace:
** 1244 printk messages dropped ** [ 9.132580]  r4:d063f400
** 7488 printk messages dropped ** [ 9.165735] Modules linked in: m25p80(+) pruss_soc_bus(+) c_can_platform(+) c_can can_dev omap_wdt(+) ahci_platform(+) libahci_platform libahci libata ti_vpe(+) ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 17891 printk messages dropped ** [ 9.243893] ------------[ cut here ]------------
** 4875 printk messages dropped ** [ 9.265474] Modules linked in: m25p80(+) pruss_soc_bus(+) c_can_platform(+) c_can can_dev omap_wdt(+) ahci_platform(+) libahci_platform libahci libata ti_vpe(+) ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 22990 printk messages dropped ** [ 9.367278] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 7197 printk messages dropped ** [ 9.398800] Backtrace:
** 3924 printk messages dropped ** [ 9.416397]  r7:00000009 r6:c0bd28c8 r5:00000000 r4:d064de20
** 9980 printk messages dropped ** [ 9.461623]  r4:00000000
** 5571 printk messages dropped ** [ 9.487301] [<c02829f8>] (irq_thread) from [<c024b004>] (kthread+0x100/0x118)
** 6617 printk messages dropped ** [ 9.517796] CPU: 0 PID: 27 Comm: irq/23-l3-app-i Tainted: G       W  O 4.9.65-rt23-06396-ga1cf405 #2
** 10733 printk messages dropped ** [ 9.566536] [<c022daac>] (warn_slowpath_fmt) from [<c0503d1c>] (l3_interrupt_handler+0x25c/0x36c)
** 9823 printk messages dropped ** [ 9.610583] [<c04d3d30>] (dump_stack) from [<c022da90>] (__warn+0xec/0x104)
** 5674 printk messages dropped ** [ 9.636958] WARNING: CPU: 0 PID: 27 at drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x25c/0x36c
** 4680 printk messages dropped ** [ 9.657502] Backtrace:
** 1219 printk messages dropped ** [ 9.663018] [<c02829f8>] (irq_thread) from [<c024b004>] (kthread+0x100/0x118)
** 6855 printk messages dropped ** [ 9.693294] [<c0503ac0>] (l3_interrupt_handler) from [<c02827b8>] (irq_forced_thread_fn+0x28/0x7c)
** 6568 printk messages dropped ** [ 9.722478] Backtrace:
** 5008 printk messages dropped ** [ 9.745453] Modules linked in: snd_soc_omap_hdmi_audio(+) pvrsrvkm(O+) m25p80 pruss_soc_bus(+) c_can_platform(+) c_can can_dev omap_wdt(+) ahci_platform(+) libahci_platform libahci libata ti_vpe(+) ti_sc ti_csc ads7846 ti_vpdma extcon_palmas rtc_palmas rtc_omap snd_soc_tlv320aic3x omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O) cmemk(O)
** 21595 printk messages dropped ** [ 9.843316] [<c04d3d30>] (dump_stack) from [<c022da90>] (__warn+0xec/0x104)
** 11123 printk messages dropped ** [ 9.893203]  r10:c0282790 r9:d063f400 r8:d0465e00 r7:00000001 r6:00000000 r5:d0465e00
** 4255 printk messages dropped ** [ 9.913370] [<c0503ac0>] (l3_interrupt_handler) from [<c02827b8>] (irq_forced_thread_fn+0x28/0x7c)

作者: beying 时间: 2019-8-2 15:53
看样子每次启动现象都不同，这次又卡在这里：
[ 8.002256] remoteproc remoteproc1: powering up 55020000.ipu
[ 8.017462] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[ 8.030580] remoteproc remoteproc1: request_firmware failed: -2
[ 8.044365] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 8.054616] remoteproc remoteproc2: powering up 40800000.dsp
[ 8.060429] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 8.069612] remoteproc remoteproc2: request_firmware failed: -2
[  OK  ] Started Network Time Synchronization.
[  OK  ] Found device /dev/ttyS2.
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[ 8.475464] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659 as rtc1
[ 8.538982] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 8.548162] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 3
[ 8.561723] spi2.0 supply vcc not found, using dummy regulator
[ 8.572779] ads7846 spi2.0: touchscreen, irq 129
[ 8.573163] input: ADS7846 Touchscreen as /devices/platform/44000000.ocp/480b8000.spi/spi_master/spi2/spi2.0/input/input1
[ 8.618456] remoteproc remoteproc3: powering up 41000000.dsp
[ 8.618465] remoteproc remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 4520256
[ 8.

我现在急于恢复，请问是重做TF卡，还是怎么弄？谢谢。

作者: 梁淑怡-Tronlong 时间: 2019-8-2 17:31
您好，
1.排查建议：试下改一下加载脚本，将bind跟ubind的时间拉长，15s左右让DSP核状态唤醒切换慢一下，看情况有没有改善；
2.板子起不来，建议直接重新制作SD启动卡测试

欢迎光临嵌入式开发者社区 (https://www.51ele.net/)