This thread has been locked.
If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.
Dear Team,
My customer found Kernel Panic occurred while their aging test.
Could you please check attached their log and let me know your opinion?
Their product is VoIP phone, and their SW is ti-processor-sdk-linux-am335x-evm-01.00.00.00. kernel version is linux-3.14.26-g2489c02.
Thanks and Best Regards,
SI.
Sorry... ^^
I attached it.
Thanks and Best Regards,
SI.
[11:17:42.0] link thread run.... [11:17:42.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12409250, Rx = 12443039 [11:17:42.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=5,cRTP=5, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2227, cAlsa=2021 [11:17:46.5] +++++> SIP_Rx_Thread : n_found=0 [11:17:47.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12409500, Rx = 12443290 [11:17:47.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=5,cRTP=5, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2231, cAlsa=2232 [11:17:49.2] @@@@@> SIP_Timer_Thread : 60 sec... [11:17:52.2] link thread run.... [11:17:52.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12409750, Rx = 12443540 [11:17:52.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=5,cRTP=5, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2217, cAlsa=2449 [11:17:56.5] +++++> SIP_Rx_Thread : n_found=0 [11:17:57.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12410000, Rx = 12443790 [11:17:57.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=5,cRTP=5, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2228, cAlsa=2133 [11:18:02.4] link thread run.... [11:18:02.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12410250, Rx = 12444039 [11:18:02.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=5,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2226, cAlsa=2552 [11:18:03.9] [AUDIO]([NO RTP(id=1)] : diffTS(265440),AlsaBuffer(2098),Time= 429863 [11:18:03.9] [AUDIO]([NO RTP(id=1)] : diffTS(265440),AlsaBuffer(1363),Time= 475543 [11:18:03.9] [AUDIO]([NO RTP(id=1)] : diffTS(265440),AlsaBuffer(1197),Time= 485897 [11:18:03.9] [AUDIO]([NO RTP(id=1)] : diffTS(265440),AlsaBuffer(1080),Time= 493292 [11:18:03.9] [AUDIO]([NO RTP(id=1)] nc_wait_rcv() diffTS(265440), AlsaBuffer(340), Time= 539464 [11:18:03.9] [MEDIA] nc_wait_rcv_A --> Drop/Prepare [11:18:04.1] [AUDIO][AM_RxData(1) : HERE=1] Len= 160, WrPtr= 55, SN= 57783, AlsaBuff=0, m_nMinLevel=6, Time= 739432 [11:18:04.6] [AUDIO][AM_RxData(1) : HERE=2] Len= 160, WrPtr= 56, SN= 57784, AlsaBuff=0, m_nMinLevel=6, Time= 298201 [11:18:05.4] [AUDIO][AM_RxData(1) : HERE=3] Len= 160, WrPtr= 57, SN= 57785, AlsaBuff=0, m_nMinLevel=6, Time= 78226 [11:18:05.6] [AUDIO](id=1) <<<<< From WAITING To SEARCHINGstate : RTPs= 9, Time= 224001>>>>> [11:18:05.6] [AUDIO](id=1) <<<<< From Searching To RUNstate : m_nMinLevel=6, LowAlsaBuffer=960, RdPtr= 58, thisSN= 57786, AlsaBuff= 960, Time= 232480>>>>> [11:18:05.6] [AUDIO](id=1) RxTS(1991055200)- MyTS(1991054400)= 800, RdPtr= 58, thisSN= 57786, AlsaBuff= 960, Time= 232911>>>>> [11:18:06.5] +++++> SIP_Rx_Thread : n_found=0 [11:18:07.7] [AUDIO]SLOW(1 more) dTS= 800, RTPs(7), AlsaBuffer(955), Time= 312356>>>>> [11:18:07.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12410500, Rx = 12444202 [11:18:07.9] [uiSys] Thread running. [11:18:10.5] [AUDIO][iD=1: D(293),0(0),PLC(0],LoD(1),LoPLC(0)= 294], aRTP=4,cRTP=3, LoNo=0,TooSK=0,Drain=1,NOaction=4, aAlsa=2008, cAlsa=1911 [11:18:12.6] link thread run.... [11:18:12.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12410750, Rx = 12444453 [11:18:15.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=4,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1785, cAlsa=1813 [11:18:16.6] +++++> SIP_Rx_Thread : n_found=0 [11:18:17.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12411000, Rx = 12444703 [11:18:20.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1793, cAlsa=1614 [11:18:22.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12411250, Rx = 12444953 [11:18:22.9] link thread run.... [11:18:25.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=4,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1761, cAlsa=1661 [11:18:26.6] +++++> SIP_Rx_Thread : n_found=0 [11:18:27.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12411500, Rx = 12445204 [11:18:30.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1796, cAlsa=1611 [11:18:32.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12411750, Rx = 12445452 [11:18:33.1] link thread run.... [11:18:35.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1789, cAlsa=1545 [11:18:36.6] +++++> SIP_Rx_Thread : n_found=0 [11:18:37.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12412000, Rx = 12445704 [11:18:40.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1779, cAlsa=1916 [11:18:42.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12412250, Rx = 12445952 [11:18:43.3] link thread run.... [11:18:43.5] [AUDIO]SLOW(1 more) dTS= 960, RTPs(8), AlsaBuffer(902), Time= 135969>>>>> [11:18:44.3] Audio_RTCP_Rx_Thread : Running(60 sec)... [11:18:45.5] [AUDIO][iD=1: D(249),0(0),PLC(0],LoD(1),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1898, cAlsa=1787 [11:18:46.6] +++++> SIP_Rx_Thread : n_found=0 [11:18:47.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12412500, Rx = 12446204 [11:18:49.3] @@@@@> SIP_Timer_Thread : 60 sec... [11:18:50.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=1, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2091, cAlsa=1955 [11:18:52.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12412750, Rx = 12446453 [11:18:53.6] link thread run.... [11:18:54.2] [uiSys] Thread running. [11:18:55.4] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2106, cAlsa=2161 [11:18:55.8] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(2076),Time= 463530 [11:18:56.6] +++++> SIP_Rx_Thread : n_found=0 [11:18:57.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12413000, Rx = 12446702 [11:19:00.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=1, aAlsa=1790, cAlsa=1834 [11:19:02.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12413250, Rx = 12446953 [11:19:03.8] link thread run.... [11:19:05.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=4,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1780, cAlsa=1614 [11:19:06.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:07.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12413500, Rx = 12447203 [11:19:10.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1800, cAlsa=1687 [11:19:12.7] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12413750, Rx = 12447453 [11:19:14.1] link thread run.... [11:19:15.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1796, cAlsa=1499 [11:19:16.3] [AUDIO]SLOW(1 more) dTS= 960, RTPs(8), AlsaBuffer(823), Time= 940838>>>>> [11:19:16.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:17.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12414000, Rx = 12447702 [11:19:20.4] [AUDIO][iD=1: D(249),0(0),PLC(0],LoD(1),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=2048, cAlsa=2206 [11:19:22.4] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(2018),Time= 87326 [11:19:22.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12414250, Rx = 12447953 [11:19:24.3] link thread run.... [11:19:25.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=1, aAlsa=1892, cAlsa=1777 [11:19:26.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:27.6] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(1485),Time= 200477 [11:19:27.6] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(1362),Time= 208183 [11:19:27.6] [AUDIO]SLOW(1 more) dTS= 480, RTPs(5), AlsaBuffer(910), Time= 255447>>>>> [11:19:27.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12414500, Rx = 12448204 [11:19:27.9] [AUDIO]SLOW(1 more) dTS= 800, RTPs(7), AlsaBuffer(914), Time= 534972>>>>> [11:19:30.5] [AUDIO][iD=1: D(248),0(0),PLC(0],LoD(2),LoPLC(0)= 250], aRTP=4,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=2, aAlsa=1749, cAlsa=1738 [11:19:32.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12414750, Rx = 12448454 [11:19:34.5] link thread run.... [11:19:35.5] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1795, cAlsa=1976 [11:19:36.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:36.6] [AUDIO]SLOW(1 more) dTS= 960, RTPs(8), AlsaBuffer(565), Time= 266649>>>>> [11:19:36.6] [AUDIO]SLOW(1 more) dTS= 640, RTPs(6), AlsaBuffer(950), Time= 293055>>>>> [11:19:37.7] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(2203),Time= 335614 [11:19:37.7] [AUDIO]([NO RTP(id=1)] : diffTS(-160),AlsaBuffer(2038),Time= 345918 [11:19:37.8] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12415000, Rx = 12448702 [11:19:40.4] [uiSys] Thread running. [11:19:40.5] [AUDIO][iD=1: D(248),0(0),PLC(0],LoD(2),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=2, aAlsa=1915, cAlsa=1511 [11:19:42.9] [AUDIO]SLOW(1 more) dTS= 800, RTPs(5), AlsaBuffer(539), Time= 429321>>>>> [11:19:42.9] [MEDIA] -EPIPE : Xrun [11:19:42.9] [MEDIA] nc_snd_pcm_A_writei(0) --> Drop/Prepare, size= 320 [11:19:43.7] [MEDIA] -EPIPE : Xrun [11:19:43.7] [MEDIA] nc_snd_pcm_readi(0) --> Drop/Prepare, size= 320 [11:19:43.7] [AUDIO]SLOW(1 more) dTS= 7680, RTPs(50), AlsaBuffer(320), Time= 347979>>>>> [11:19:44.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12415250, Rx = 12449019 [11:19:44.3] [AUDIO]SLOW(1 more) dTS= 11040, RTPs(7), AlsaBuffer(632), Time= 921562>>>>> [11:19:44.4] [AUDIO]SLOW(1 more) dTS= 11040, RTPs(6), AlsaBuffer(862), Time= 6896>>>>> [11:19:45.1] link thread run.... [11:19:46.4] [AUDIO]SLOW(1 more) dTS= 10880, RTPs(6), AlsaBuffer(958), Time= 41201>>>>> [11:19:46.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:46.7] [AUDIO][iD=1: D(245),0(0),PLC(0],LoD(5),LoPLC(0)= 250], aRTP=4,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1587, cAlsa=2142 [11:19:46.9] [AUDIO]SLOW(1 more) dTS= 11040, RTPs(7), AlsaBuffer(912), Time= 524442>>>>> [11:19:48.5] Audio_RTCP_Rx_Thread : Running(60 sec)... [11:19:49.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12415500, Rx = 12449272 [11:19:49.5] @@@@@> SIP_Timer_Thread : 60 sec... [11:19:50.7] [AUDIO]([NO RTP(id=1)] : diffTS(10080),AlsaBuffer(2118),Time= 309910 [11:19:51.7] [AUDIO][iD=1: D(249),0(0),PLC(0],LoD(1),LoPLC(0)= 250], aRTP=2,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=1, aAlsa=1990, cAlsa=1586 [11:19:54.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12415750, Rx = 12449522 [11:19:55.3] link thread run.... [11:19:56.6] +++++> SIP_Rx_Thread : n_found=0 [11:19:56.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1758, cAlsa=1451 [11:19:59.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12416000, Rx = 12449772 [11:20:01.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1758, cAlsa=1870 [11:20:04.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12416250, Rx = 12450021 [11:20:05.6] link thread run.... [11:20:06.6] +++++> SIP_Rx_Thread : n_found=0 [11:20:06.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1789, cAlsa=1522 [11:20:09.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12416500, Rx = 12450271 [11:20:11.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1794, cAlsa=2127 [11:20:14.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12416750, Rx = 12450523 [11:20:15.8] link thread run.... [11:20:16.6] +++++> SIP_Rx_Thread : n_found=0 [11:20:16.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=4, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1774, cAlsa=1593 [11:20:19.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12417000, Rx = 12450772 [11:20:21.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1801, cAlsa=1655 [11:20:22.5] [AUDIO]SLOW(1 more) dTS= 10880, RTPs(5), AlsaBuffer(947), Time= 138489>>>>> [11:20:23.4] [AUDIO]([NO RTP(id=1)] : diffTS(10080),AlsaBuffer(2066),Time= 93477 [11:20:23.5] [AUDIO]([NO RTP(id=1)] : diffTS(10080),AlsaBuffer(1698),Time= 136135 [11:20:23.8] [AUDIO]SLOW(1 more) dTS= 11040, RTPs(7), AlsaBuffer(838), Time= 408972>>>>> [11:20:24.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12417250, Rx = 12451022 [11:20:26.0] link thread run.... [11:20:26.6] +++++> SIP_Rx_Thread : n_found=0 [11:20:26.7] [AUDIO][iD=1: D(248),0(0),PLC(0],LoD(2),LoPLC(0)= 250], aRTP=3,cRTP=1, LoNo=0,TooSK=0,Drain=0,NOaction=2, aAlsa=1808, cAlsa=2090 [11:20:27.6] [uiSys] Thread running. [11:20:29.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12417500, Rx = 12451272 [11:20:31.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1751, cAlsa=1742 [11:20:34.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12417750, Rx = 12451522 [11:20:36.3] link thread run.... [11:20:36.7] +++++> SIP_Rx_Thread : n_found=0 [11:20:36.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1776, cAlsa=1985 [11:20:39.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12418000, Rx = 12451773 [11:20:41.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1795, cAlsa=1792 [11:20:44.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12418250, Rx = 12452022 [11:20:46.5] link thread run.... [11:20:46.7] +++++> SIP_Rx_Thread : n_found=0 [11:20:46.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1771, cAlsa=1864 [11:20:49.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12418500, Rx = 12452270 [11:20:49.6] @@@@@> SIP_Timer_Thread : 60 sec... [11:20:51.6] Audio_RTCP_Rx_Thread : Running(60 sec)... [11:20:51.8] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1781, cAlsa=1671 [11:20:54.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12418750, Rx = 12452521 [11:20:56.7] +++++> SIP_Rx_Thread : n_found=0 [11:20:56.7] link thread run.... [11:20:56.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=2, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1779, cAlsa=1882 [11:20:59.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12419000, Rx = 12452772 [11:21:01.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=1, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1773, cAlsa=2147 [11:21:04.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12419250, Rx = 12453021 [11:21:06.7] +++++> SIP_Rx_Thread : n_found=0 [11:21:06.7] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1751, cAlsa=1815 [11:21:06.9] link thread run.... [11:21:08.2] [AUDIO]SLOW(1 more) dTS= 10720, RTPs(5), AlsaBuffer(872), Time= 866824>>>>> [11:21:08.9] [AUDIO]([NO RTP(id=1)] : diffTS(10080),AlsaBuffer(2136),Time= 529064 [11:21:09.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12419500, Rx = 12453272 [11:21:11.7] [AUDIO][iD=1: D(249),0(0),PLC(0],LoD(1),LoPLC(0)= 250], aRTP=3,cRTP=3, LoNo=0,TooSK=0,Drain=0,NOaction=1, aAlsa=1812, cAlsa=1776 [11:21:13.8] [uiSys] Thread running. [11:21:14.1] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12419750, Rx = 12453521 [11:21:16.2] [MEDIA] -EPIPE : Xrun [11:21:16.2] [MEDIA] nc_snd_pcm_A_writei(0) --> Drop/Prepare, size= 320 [11:21:16.2] [AUDIO]SLOW(1 more) dTS= 11840, RTPs(10), AlsaBuffer(0), Time= 800823>>>>> [11:21:16.7] +++++> SIP_Rx_Thread : n_found=0 [11:21:16.9] [MEDIA] -EPIPE : Xrun [11:21:16.9] [MEDIA] nc_snd_pcm_readi(0) --> Drop/Prepare, size= 320 [11:21:17.0] [AUDIO]SLOW(1 more) dTS= 17600, RTPs(47), AlsaBuffer(640), Time= 579081>>>>> [11:21:17.5] [AUDIO]SLOW(1 more) dTS= 21600, RTPs(9), AlsaBuffer(881), Time= 185043>>>>> [11:21:17.6] link thread run.... [11:21:18.1] [AUDIO][iD=1: D(247),0(0),PLC(0],LoD(3),LoPLC(0)= 250], aRTP=4,cRTP=8, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1698, cAlsa=1279 [11:21:18.4] [AUDIO]SLOW(1 more) dTS= 21760, RTPs(10), AlsaBuffer(826), Time= 68460>>>>> [11:21:20.6] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12420000, Rx = 12453842 [11:21:23.1] [AUDIO][iD=1: D(249),0(0),PLC(0],LoD(1),LoPLC(0)= 250], aRTP=6,cRTP=6, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1787, cAlsa=1826 [11:21:25.6] @@@@@@@@(Tx)@@@@@@@@ Audio_RTP(1) : Tx = 12420250, Rx = 12454094 [11:21:26.7] +++++> SIP_Rx_Thread : n_found=0 [11:21:27.8] link thread run.... [11:21:28.1] [AUDIO][iD=1: D(250),0(0),PLC(0],LoD(0),LoPLC(0)= 250], aRTP=6,cRTP=5, LoNo=0,TooSK=0,Drain=0,NOaction=0, aAlsa=1780, cAlsa=1920 [11:21:28.7] [263891.487277] Unable to handle kernel NULL pointer dereference at virtual address 00000050 [11:21:28.7] [263891.495910] pgd = c0004000 [11:21:28.7] [263891.498847] [00000050] *pgd=00000000 [11:21:28.7] [263891.502716] Internal error: Oops: 17 [#1] ARM [11:21:28.7] [263891.507395] Modules linked in: [11:21:28.7] [263891.510712] CPU: 0 PID: 3 Comm: ksoftirqd/0 Not tainted 3.14.26-gb9df364-dirty #239 [11:21:28.7] [263891.518868] task: c5866400 ti: c5878000 task.ti: c5878000 [11:21:28.7] [263891.524660] PC is at tick_sched_handle+0x3c/0x5c [11:21:28.7] [263891.529617] LR is at tick_sched_timer+0x48/0x78 [11:21:28.7] [263891.534478] pc : [<c0070710>] lr : [<c00708c4>] psr: 60000093 [11:21:28.7] [263891.534478] sp : c5879e00 ip : c5879e10 fp : c5879e0c [11:21:28.7] [263891.546743] r10: c0885688 r9 : 00000000 r8 : 00000000 [11:21:28.7] [263891.552328] r7 : 00000010 r6 : c0886c78 r5 : 0000f002 r4 : 05ed370f [11:21:28.7] [263891.559281] r3 : 00000000 r2 : 0002aabd r1 : 00000010 r0 : c0886c78 [11:21:28.7] [263891.566247] Flags: nZCv IRQs off FIQs on Mode SVC_32 ISA ARM Segment kernel [11:21:28.7] [263891.574127] Control: 10c5387d Table: 8489c019 DAC: 00000015 [11:21:28.7] [263891.580267] Process ksoftirqd/0 (pid: 3, stack limit = 0xc5878238) [11:21:28.7] [263891.586861] Stack: (0xc5879e00 to 0xc587a000) [11:21:28.7] [263891.591541] 9e00: c5879e3c c5879e10 c00708c4 c00706e0 00000000 c0886c78 05ed370f 0000f002 [11:21:28.7] [263891.600239] 9e20: c0886c78 c08856b8 c08856b8 00000000 c5879e54 c5879e40 c0050d98 c0070888 [11:21:28.7] [263891.608946] 9e40: 05ed2404 0000f002 c5879eac c5879e58 c00514b4 c0050d64 05ed2404 0000f002 [11:21:28.7] [263891.617653] 9e60: c05fef84 00000003 05ed2404 0000f002 ffffffff 7fffffff 05ed2404 0000f002 [11:21:28.7] [263891.626359] 9e80: c0885b38 20000013 c08c9de0 c08c9dd0 c5878000 00000004 00000100 00000008 [11:21:28.7] [263891.635052] 9ea0: c5879ec4 c5879eb0 c0051680 c00513e0 c0886020 00000000 c5879ed4 c5879ec8 [11:21:28.7] [263891.643760] 9ec0: c00516a0 c0051654 c5879f1c c5879ed8 c00390a0 c005169c c05fd340 c0008630 [11:21:28.7] [263891.652461] 9ee0: 04208040 0192356c 0000000a c08c9dc0 c5870fc0 c5870fc0 c5878000 c0883e50 [11:21:28.7] [263891.661157] 9f00: 00000001 00000000 00000000 00000002 c5879f34 c5879f20 c00391e8 c0038fc4 [11:21:28.7] [263891.669866] 9f20: c05fb014 00000000 c5879f64 c5879f38 c0054afc c00391bc c5866400 00000000 [11:21:28.7] [263891.678569] 9f40: c5870f80 c5870fc0 c0054a10 00000000 00000000 00000000 c5879fac c5879f68 [11:21:28.7] [263891.687278] 9f60: c004e5e8 c0054a1c ecdfbfbd 00000001 00000000 c5870fc0 00000000 c5879f7c [11:21:28.7] [263891.695979] 9f80: c5879f7c 00000000 c5879f88 c5879f88 c5870f80 c004e524 00000000 00000000 [11:21:28.7] [263891.704684] 9fa0: 00000000 c5879fb0 c000e978 c004e530 00000000 00000000 00000000 00000000 [11:21:28.7] [263891.713386] 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 [11:21:28.7] [263891.722092] 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000 fff817bb bf5f3b75 [11:21:28.7] [263891.730786] Backtrace: [11:21:28.7] [263891.733468] [<c00706d4>] (tick_sched_handle) from [<c00708c4>] (tick_sched_timer+0x48/0x78) [11:21:28.7] [263891.742366] [<c007087c>] (tick_sched_timer) from [<c0050d98>] (__run_hrtimer.isra.22+0x40/0xc0) [11:21:28.7] [263891.751618] r7:00000000 r6:c08856b8 r5:c08856b8 r4:c0886c78 [11:21:28.7] [263891.757698] [<c0050d58>] (__run_hrtimer.isra.22) from [<c00514b4>] (hrtimer_interrupt+0xe0/0x274) [11:21:28.7] [263891.767127] r5:0000f002 r4:05ed2404 [11:21:28.7] [263891.770997] [<c00513d4>] (hrtimer_interrupt) from [<c0051680>] (hrtimer_peek_ahead_timers+0x38/0x48) [11:21:28.7] [263891.780693] r10:00000008 r9:00000100 r8:00000004 r7:c5878000 r6:c08c9dd0 r5:c08c9de0 [11:21:28.7] [263891.789054] r4:20000013 [11:21:28.8] [263891.791825] [<c0051648>] (hrtimer_peek_ahead_timers) from [<c00516a0>] (run_hrtimer_softirq+0x10/0x14) [11:21:28.8] [263891.801706] r4:00000000 r3:c0886020 [11:21:28.8] [263891.805582] [<c0051690>] (run_hrtimer_softirq) from [<c00390a0>] (__do_softirq+0xe8/0x1f8) [11:21:28.8] [263891.814373] [<c0038fb8>] (__do_softirq) from [<c00391e8>] (run_ksoftirqd+0x38/0x50) [11:21:28.8] [263891.822519] r10:00000002 r9:00000000 r8:00000000 r7:00000001 r6:c0883e50 r5:c5878000 [11:21:28.8] [263891.830877] r4:c5870fc0 [11:21:28.8] [263891.833650] [<c00391b0>] (run_ksoftirqd) from [<c0054afc>] (smpboot_thread_fn+0xec/0x148) [11:21:28.8] [263891.842356] [<c0054a10>] (smpboot_thread_fn) from [<c004e5e8>] (kthread+0xc4/0xe0) [11:21:28.8] [263891.850406] r10:00000000 r9:00000000 r8:00000000 r7:c0054a10 r6:c5870fc0 r5:c5870f80 [11:21:28.8] [263891.858763] r4:00000000 r3:c5866400 [11:21:28.8] [263891.862639] [<c004e524>] (kthread) from [<c000e978>] (ret_from_fork+0x14/0x3c) [11:21:28.8] [263891.870329] r7:00000000 r6:00000000 r5:c004e524 r4:c5870f80 [11:21:28.8] [263891.876401] Code: e3530000 05903048 02833001 05803048 (e5913040) [11:21:28.8] [263891.884133] ---[ end trace 85239bc97cf544aa ]--- [11:21:28.8] [263891.889090] Kernel panic - not syncing: Fatal exception in interrupt
Hello SI,
I think the crash occurred here in kernel/time/tick-sched.c they can check where the program counter was by executing addr2line -f -e vmlinux c0070710 command
static enum hrtimer_restart tick_sched_timer(struct hrtimer *timer)
{
struct tick_sched *ts =
container_of(timer, struct tick_sched, sched_timer);
struct pt_regs *regs = get_irq_regs();
ktime_t now = ktime_get();
tick_sched_do_timer(now);
/*
* Do not call, when we are not in irq context and have
* no valid regs pointer
*/
if (regs)
tick_sched_handle(ts, regs)
and they should try to figure out why their aging test arose this panic. In my opinion they have reached the allowed performance, so that crash occurred.
If anyone else have more tips and objective opinion, please share.
Best regards,
Kemal