Opened 8 years ago
Closed 8 years ago
#198 closed баг (дубликат)
Односторонний обтой после разговора
Reported by: | alx | Owned by: | alx |
---|---|---|---|
Priority: | средний | Milestone: | 1 очередь |
Component: | VE-01 | Keywords: | |
Cc: |
Description
На нашей АТС обнаружена следующая ситуация:
- Пришел вызов в порт FXO, была вызвана группа 1002.
- Вызов был перенаправлен членам группы 1002: 114, 115 и 116.
- Абонент 115 ответил на вызов, состоялся разговор.
- Абонент 115 положил трубку, но отбоя FXO не произошло. В логах сообщений об ошибках нет, но видно, что сразу после отбоя абоненту 115 пришло событие eMessageFailed.
Вот лог:
Nov 8 05:29:09 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Idle: CAS event, ts=34, flags=0000, data=7 Nov 8 05:29:10 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Ringing: CAS event, ts=34, flags=0000, data=15 Nov 8 05:29:10 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Call Progress detected, ts=33, flags=0000, data=53 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Ringing: CAS event, ts=34, flags=0000, data=7 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:302: ts 34: dialing 1002@192.168.0.4 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: user_agent.cpp:2473: --> ua_dial_out() "223-20-42" <sip:fxo2@192.168.0.4> -> sip:1002@192.168.0.4 ()... Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1477: INVITE received: sip:114@127.0.0.1:6060;transport=udp (Call-ID: 428766686@(null)) Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=470 Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1477: INVITE received: sip:115@127.0.0.1:6060;transport=udp (Call-ID: 428766686@(null)) Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1477: INVITE received: sip:116@127.0.0.1:6060;transport=udp (Call-ID: 428766686@(null)) Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:901: channel 9: ringing Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: user_agent.cpp:2841: Call 470 routed to TS 9 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=9, state=Ringing: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=472 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:901: channel 10: ringing Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: user_agent.cpp:2841: Call 472 routed to TS 10 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=9, state=Ringing: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Ringing: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=474 Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1604: ---> ringing with status code 180 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxs.cpp:901: channel 11: ringing Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: user_agent.cpp:2841: Call 474 routed to TS 11 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: Remote is ringing, ts=34, flags=0000, data=469 Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1604: ---> ringing with status code 180 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: Remote is ringing, ts=34, flags=0000, data=469 Nov 8 05:29:13 comcerto daemon.info sip_ua[1863]: user_agent.cpp:1604: ---> ringing with status code 180 Nov 8 05:29:13 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: Remote is ringing, ts=34, flags=0000, data=469 Nov 8 05:29:14 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: CAS event, ts=34, flags=0000, data=15 Nov 8 05:29:17 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: CAS event, ts=34, flags=0000, data=7 Nov 8 05:29:18 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: CAS event, ts=34, flags=0000, data=15 Nov 8 05:29:18 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:18 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:18 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:18 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Ringing: CAS event, ts=10, flags=0000, data=7 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4015: channel 10: answer Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: RTP parameters, ts=10, flags=0000, data=472 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4814: --> ts 10: 192.168.0.4[10020] --> 192.168.0.4[10068] Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4815: --> ts 10: codec PCMA, VAD is on Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4816: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4916: ts 10: starting RTP stream to 02:ad:c3:00:00:b4 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: RTP parameters, ts=34, flags=0000, data=469 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4814: --> ts 34: 192.168.0.4[10068] --> 192.168.0.4[10020] Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4815: --> ts 34: codec PCMA, VAD is on Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4816: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: comcerto.cpp:4916: ts 34: starting RTP stream to 02:ad:c3:00:00:b4 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Calling: Call answered, ts=34, flags=0000, data=469 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:19 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:20 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=11, state=Ringing: Call disconnected, ts=11, flags=0000, data=474 Nov 8 05:29:20 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=11, state=Idle: Call disconnected, ts=11, flags=0000, data=474 Nov 8 05:29:20 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=9, state=Ringing: Call disconnected, ts=9, flags=0000, data=470 Nov 8 05:29:20 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=9, state=Idle: Call disconnected, ts=9, flags=0000, data=470 Nov 8 05:29:20 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: ACK received, ts=10, flags=0000, data=472 Nov 8 05:29:23 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:23 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:23 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:23 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:24 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:24 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:24 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:24 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:27 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Call Progress detected, ts=34, flags=0000, data=53 Nov 8 05:29:27 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Call Progress detected, ts=34, flags=0000, data=53 Nov 8 05:29:28 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:28 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:28 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:28 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:28 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Call Progress detected, ts=34, flags=0000, data=53 Nov 8 05:29:29 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:29 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:29 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:29 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:31 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Call Progress detected, ts=34, flags=0000, data=53 Nov 8 05:29:32 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Call Progress detected, ts=34, flags=0000, data=53 Nov 8 05:29:33 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:33 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:33 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:33 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:34 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:34 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: Statistics received, ts=10, flags=0000, data=0 Nov 8 05:29:34 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:34 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:36 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Connected: CAS event, ts=10, flags=0000, data=15 Nov 8 05:29:37 comcerto daemon.info sip_ua[1959]: repro.cpp:510: --> duration=1478582977, []:@: ---> []:@: Nov 8 05:29:37 comcerto daemon.info sip_ua[1959]: repro.cpp:556: --> CDR: {"answer":0,"duration":1478582977,"hangup":1478582977,"org-host":"","org-scheme":"","org-user":"","to-host":"","to-scheme":"","to-user":"","type":""} Nov 8 05:29:37 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Idle: Call disconnected, ts=10, flags=0000, data=472 Nov 8 05:29:37 comcerto daemon.info sip_ua[1959]: repro.cpp:510: --> duration=1478582977, []:@: ---> []:@: Nov 8 05:29:37 comcerto daemon.info sip_ua[1959]: repro.cpp:556: --> CDR: {"answer":0,"duration":1478582977,"hangup":1478582977,"org-host":"","org-scheme":"","org-user":"","to-host":"","to-scheme":"","to-user":"","type":""} Nov 8 05:29:37 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=10, state=Idle: Message failed, ts=10, flags=0000, data=472 Nov 8 05:29:38 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:38 comcerto daemon.info sip_ua[1841]: fxs.cpp:399: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0 Nov 8 05:29:38 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:38 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=33, state=Connected: Statistics received, ts=33, flags=0000, data=0 Nov 8 05:29:39 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0 Nov 8 05:29:39 comcerto daemon.info sip_ua[1841]: fxo.cpp:344: ---> ts=34, state=Connected: Statistics received, ts=34, flags=0000, data=0
Note:
See TracTickets
for help on using tickets.
Получен лог SIP для похожего случая. Вот что происходило при отбое:
А вот что было, когда вызов был отбит командой из веб-интерфейса блока: