прошивка: 1.02.38.43
Ни с того ни с сего перестал нормально фунциклировать FXO P1. В свою очередь P2, P3, P4 - работают нормально.
Симптомы: В FXO1 включена аналоговая линия, FXO1 нормально регистрируется на *, настроен HotLine при звонке на этот FXO.
Звоним на него, CallerID не определяется, идет якобы дозвон на hotline, * нормально это обрабатывает, а вот у абонента который звонит на FXO1 обычные гудки, и вообще никакой реакции на поднятие трубки астериском(людьми, они кстати не слышат ничего в трубке - тишина).
Вот slmon лог такого звонка:
Код:
19:27:27 [004432] 0: Fxo Ringing
19:27:27 [004433] 0: RingTime(61) First Stop
19:27:28 [004446] 0: ===== New FxoLineStatus 1
19:27:29 [004452] 0: RingTime(257) Second Ring
19:27:31 [004472] 0: CallerId=
19:27:31 [004472] 0: ==17:WaitAnswerDeviceOk
19:27:31 [004472] 0: HotLineEnabled with [602]
19:27:31 [004472] 0: End Input Default Route [1]
19:27:31 [004472] 0: Call Proxy with [602]
19:27:31 [004472] 0: ==7:Inviting
19:27:31 [004472] 0: VoIP CallOut, Invite <sip:602@192.168.18.190;user=phone>
19:27:31 [004474] 0: 501=DIALING
19:27:31 [004477] 0: 501=PROCEEDING
19:27:32 [004478] 0: 501=CONNECTED
19:27:32 [004478] 0: Peer=192.168.18.190:16734, PT=0, RecvOnly=0
19:27:32 [004478] 0: FxoHookOff
19:27:32 [004478] 0: SetInputGain(10)
19:27:32 [004478] 0: RtpApiTalk[1,1], Peer=192.168.18.190:16734, PT=0, FC=2, NewOOB=1
19:27:32 [004478] 0: ==14:Talking
19:28:28 [004975] 0: Fax Detected [ced]
19:28:32 [005025] 0: Fax Preamble Timeout
19:28:32 [005025] DSP_ch0_check=0
19:28:34 [005039] 0: 501=SDPCHANGING
19:28:34 [005039] T38 UDP
19:28:34 [005039] 0: Substatus=8
19:28:34 [005040] 0: 501=ACCEPT
19:28:34 [005041] 0: 501=CONNECTED
19:28:34 [005041] 0: St_Talking/Substatus=8
19:28:34 [005041] 0: Start T38 to 192.168.18.190:4481, protocol=0
19:28:34 [005041] 0: Substatus=0
19:29:03 [005396] 0: 501=DISCONNECT
19:29:03 [005396] 0: Release Active Dlg
19:29:03 [005398] 0: ==15:PlayBusyTone
19:29:05 [005420] 0: Fxo Detected CPT Busy(2)
19:29:05 [005420] DSP_ch0_check=0
19:29:05 [005420] 0: FxoHookOn
19:29:05 [005420] 0: ==3:Idle
19:29:05 [005420] 0: SetInputGain(4)
19:29:05 [005420] 0: SetFax(1)=0
19:29:05 [005420] 0: ===== New FxoLineStatus 0
лог *:
Код:
[Dec 6 19:27:35] -- Executing [602@sitibel-pstn:1] Set("SIP/501-00000017", "pseudodid="501" <sip:501@192.168.18.190;user=phone>;tag=52fbd8ab-685257") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel-pstn:2] Set("SIP/501-00000017", "pseudodid=501") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel-pstn:3] Set("SIP/501-00000017", "CALLERID(name)=501 GTS Grodno") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel-pstn:4] Set("SIP/501-00000017", "CALLERID(num)=501") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel-pstn:5] Goto("SIP/501-00000017", "sitibel,602,1") in new stack
[Dec 6 19:27:35] -- Goto (sitibel,602,1)
[Dec 6 19:27:35] -- Executing [602@sitibel:1] Answer("SIP/501-00000017", "") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel:2] MixMonitor("SIP/501-00000017", ""[2013.12.06-19.27.35] (in) 501-CC_SitiBel.wav",b") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel:3] Set("SIP/501-00000017", "MIXMSTATUS=1") in new stack
[Dec 6 19:27:35] -- Executing [602@sitibel:4] BackGround("SIP/501-00000017", "privetstvie") in new stack
[Dec 6 19:27:35] == Begin MixMonitor Recording SIP/501-00000017
[Dec 6 19:27:35] -- <SIP/501-00000017> Playing 'privetstvie.slin' (language 'ru')
[Dec 6 19:27:43] -- Executing [602@sitibel:5] WaitExten("SIP/501-00000017", "5") in new stack
[Dec 6 19:27:48] -- Timeout on SIP/501-00000017, continuing...
[Dec 6 19:27:48] -- Executing [602@sitibel:6] Goto("SIP/501-00000017", "sitibel,5,1") in new stack
[Dec 6 19:27:48] -- Goto (sitibel,5,1)
[Dec 6 19:27:48] -- Executing [5@sitibel:1] Dial("SIP/501-00000017", "SIP/201&SIP/202&SIP/203,15,Tt") in new stack
[Dec 6 19:27:48] -- Called SIP/201
[Dec 6 19:27:48] -- Called SIP/202
[Dec 6 19:27:48] -- Called SIP/203
[Dec 6 19:27:48] -- SIP/201-00000018 connected line has changed. Saving it until answer for SIP/501-00000017
[Dec 6 19:27:48] -- SIP/202-00000019 connected line has changed. Saving it until answer for SIP/501-00000017
[Dec 6 19:27:48] -- SIP/203-0000001a connected line has changed. Saving it until answer for SIP/501-00000017
[Dec 6 19:27:48] -- SIP/201-00000018 is ringing
[Dec 6 19:27:49] -- SIP/202-00000019 is ringing
[Dec 6 19:27:49] -- SIP/203-0000001a is ringing
[Dec 6 19:27:49] -- SIP/202-00000019 is ringing
[Dec 6 19:27:49] -- SIP/203-0000001a is ringing
[Dec 6 19:27:49] -- SIP/203-0000001a is ringing
[Dec 6 19:28:04] -- Nobody picked up in 15000 ms
[Dec 6 19:29:07] -- Executing [h@sitibel:1] NoOp("SIP/501-00000017", "Hangup detected.") in new stack
[Dec 6 19:29:07] -- Executing [h@sitibel:2] GotoIf("SIP/501-00000017", "1?h,mixmstop") in new stack
[Dec 6 19:29:07] -- Goto (sitibel,h,4)
[Dec 6 19:29:07] -- Executing [h@sitibel:4] StopMixMonitor("SIP/501-00000017", "") in new stack
[Dec 6 19:29:07] == MixMonitor close filestream
[Dec 6 19:29:07] -- Executing [h@sitibel:5] Hangup("SIP/501-00000017", "") in new stack
[Dec 6 19:29:07] == Spawn extension (sitibel, h, 5) exited non-zero on 'SIP/501-00000017'
[Dec 6 19:29:07] == Spawn extension (sitibel, 5, 2) exited non-zero on 'SIP/501-00000017'
[Dec 6 19:29:07] == End MixMonitor Recording SIP/501-00000017
ок, думаю может с АТС что то криво приходит, переставляю аналоговую линию в FXO2, звоню на нее и все ок.
лог slmon:
Код:
19:46:03 [015557] 1: Fxo Ringing
19:46:03 [015558] 1: RingTime(59) First Stop
19:46:05 [015576] 1: RingTime(255) Second Ring
19:46:07 [015597] 1: ### Fxo Get Fsk CallerId [80152573814,], Len=11
19:46:07 [015597] 1: CallerId=80152573814
19:46:07 [015597] 1: ==17:WaitAnswerDeviceOk
19:46:07 [015597] 1: HotLineEnabled with [602]
19:46:07 [015597] 1: End Input Default Route [1]
19:46:07 [015597] 1: Call Proxy with [602]
19:46:07 [015597] 1: ==7:Inviting
19:46:07 [015597] 1: VoIP CallOut, Invite <sip:602@192.168.18.190;user=phone>
19:46:07 [015599] 1: 502=DIALING
19:46:07 [015602] 1: 502=PROCEEDING
19:46:07 [015603] 1: 502=CONNECTED
19:46:07 [015603] 1: Peer=192.168.18.190:18962, PT=0, RecvOnly=0
19:46:07 [015603] 1: FxoHookOff
19:46:07 [015603] 1: SetInputGain(10)
19:46:07 [015603] 1: RtpApiTalk[1,1], Peer=192.168.18.190:18962, PT=0, FC=2, NewOOB=1
19:46:07 [015603] 1: ==14:Talking
19:46:26 [015788] 1: Fxo Detected BTC(3)
19:46:26 [015788] 1: Bye at Talking
19:46:26 [015788] 1: ==15:PlayBusyTone
19:46:26 [015789] DSP_ch1_check=0
19:46:26 [015789] 1: FxoHookOn
19:46:26 [015789] 1: ==3:Idle
19:46:26 [015789] 1: SetInputGain(4)
19:46:26 [015789] 1: SetFax(1)=0
19:46:26 [015789] 1: 502=DISCONNECT
19:46:26 [015790] 1: Release Old Dlg #2
лог *:
Код:
[Dec 6 19:49:42] -- Executing [602@sitibel-pstn:1] Set("SIP/502-00000025", "pseudodid="80152573814" <sip:502@192.168.18.190;user=phone>;tag=1e387c1a-686583") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel-pstn:2] Set("SIP/502-00000025", "pseudodid=80152573814") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel-pstn:3] Set("SIP/502-00000025", "CALLERID(name)=80152573814 GTS Grodno") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel-pstn:4] Set("SIP/502-00000025", "CALLERID(num)=80152573814") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel-pstn:5] Goto("SIP/502-00000025", "sitibel,602,1") in new stack
[Dec 6 19:49:42] -- Goto (sitibel,602,1)
[Dec 6 19:49:42] -- Executing [602@sitibel:1] Answer("SIP/502-00000025", "") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel:2] MixMonitor("SIP/502-00000025", ""[2013.12.06-19.49.42] (in) 80152573814-CC_SitiBel.wav",b") in new stack
[Dec 6 19:49:42] -- Executing [602@sitibel:3] Set("SIP/502-00000025", "MIXMSTATUS=1") in new stack
[Dec 6 19:49:42] == Begin MixMonitor Recording SIP/502-00000025
[Dec 6 19:49:42] -- Executing [602@sitibel:4] BackGround("SIP/502-00000025", "privetstvie") in new stack
[Dec 6 19:49:42] -- <SIP/502-00000025> Playing 'privetstvie.slin' (language 'ru')
[Dec 6 19:49:50] -- Executing [602@sitibel:5] WaitExten("SIP/502-00000025", "5") in new stack
[Dec 6 19:49:55] -- Timeout on SIP/502-00000025, continuing...
[Dec 6 19:49:55] -- Executing [602@sitibel:6] Goto("SIP/502-00000025", "sitibel,5,1") in new stack
[Dec 6 19:49:55] -- Goto (sitibel,5,1)
[Dec 6 19:49:55] -- Executing [5@sitibel:1] Dial("SIP/502-00000025", "SIP/201&SIP/202&SIP/203,15,Tt") in new stack
[Dec 6 19:49:55] -- Called SIP/201
[Dec 6 19:49:55] -- Called SIP/202
[Dec 6 19:49:55] -- Called SIP/203
[Dec 6 19:49:55] -- SIP/201-00000026 connected line has changed. Saving it until answer for SIP/502-00000025
[Dec 6 19:49:55] -- SIP/202-00000027 connected line has changed. Saving it until answer for SIP/502-00000025
[Dec 6 19:49:55] -- SIP/203-00000028 connected line has changed. Saving it until answer for SIP/502-00000025
[Dec 6 19:49:55] -- SIP/201-00000026 is ringing
[Dec 6 19:49:55] -- SIP/202-00000027 is ringing
[Dec 6 19:49:56] -- SIP/203-00000028 is ringing
[Dec 6 19:49:56] -- SIP/202-00000027 is ringing
[Dec 6 19:49:56] -- SIP/203-00000028 is ringing
[Dec 6 19:49:56] -- SIP/203-00000028 is ringing
[Dec 6 19:50:00] == Spawn extension (sitibel, 5, 1) exited non-zero on 'SIP/502-00000025'
[Dec 6 19:50:00] -- Executing [h@sitibel:1] NoOp("SIP/502-00000025", "Hangup detected.") in new stack
[Dec 6 19:50:00] -- Executing [h@sitibel:2] GotoIf("SIP/502-00000025", "1?h,mixmstop") in new stack
[Dec 6 19:50:00] -- Goto (sitibel,h,4)
[Dec 6 19:50:00] -- Executing [h@sitibel:4] StopMixMonitor("SIP/502-00000025", "") in new stack
[Dec 6 19:50:00] -- Executing [h@sitibel:5] Hangup("SIP/502-00000025", "") in new stack
[Dec 6 19:50:00] == Spawn extension (sitibel, h, 5) exited non-zero on 'SIP/502-00000025'
[Dec 6 19:50:00] == End MixMonitor Recording SIP/502-00000025
В логах slmon сразу в глаза бросается 3я строчка фейловых звонков с FxoLineStatus - первое же отличие между нормальными звонками и ненормальными.
Куда копать? не понимаю - все работало, ничего не менялось и тут как снег на голову.