При входящем/исходящем вызове примерно через 30 секунд разговора TAU-8 бросает трубку.
Посмотрите пожалуйста почему так может происходить.
Лог прикладываю.
Версия ПО: #1.9.0-ru
Код: Выделить всё
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Via: SIP/2.0/UDP 192.168.99.77;rport;branch=z9hG4bKB3t3gNFBUpXjK
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Max-Forwards: 70
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] To: <sip:101@5193031_vats>
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Call-ID: 64bf41ba-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] CSeq: 71636901 NOTIFY
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] User-Agent: TAU-8.IP/1.9.0 SN/VI20000058 sofia-sip/1.12.10
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Event: keep-alive
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Content-Length: 0
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip]
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] ------------------------------------------------------------------------
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip]recv 333 bytes from udp/[192.168.99.12]:5060 at 20:33:10.905433:
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] ------------------------------------------------------------------------
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] SIP/2.0 501 Method NOTIFY not implemented
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Via: SIP/2.0/UDP 192.168.99.77:5060;rport=5060;branch=z9hG4bKB3t3gNFBUpXjK;received=192.168.99.77
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] To: <sip:101@5193031_vats>
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Call-ID: 64bf41ba-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] CSeq: 71636901 NOTIFY
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Server: TS-v4.5.1-18fW
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] Content-Length: 0
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip]
Feb 14 23:33:10 OpenWrt user.notice syslog: 23:33:10.906[sip] ------------------------------------------------------------------------
Feb 14 23:33:13 OpenWrt user.debug syslog: 23:33:13.516[app:dbg]Chan 6: send rtcp
Feb 14 23:33:19 OpenWrt user.debug syslog: 23:33:19.516[app:dbg]Chan 6: send rtcp
Feb 14 23:33:24 OpenWrt user.debug syslog: 23:33:24.516[app:dbg]Chan 6: send rtcp
Feb 14 23:33:28 OpenWrt user.debug syslog: 23:33:28.016[app:dbg]Chan 6: send rtcp
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip]recv 625 bytes from udp/[192.168.99.12]:5060 at 20:33:30.815433:
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] ------------------------------------------------------------------------
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] BYE sip:101@192.168.99.77:5060 SIP/2.0
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Via: SIP/2.0/UDP 192.168.99.12:5060;rport;branch=z9hG4bK-975337220-3826354356-731956899-31610715441
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Via: SIP/2.0/UDP 192.168.99.12:5063;rport=5063;branch=z9hG4bK-975337220-3826354356-731956899-3161071544;received=192.168.99.12
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] From: <sip:102@5193031_vats>;tag=1926960881-3826354100-731956899-3161071544
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] To: "101" <sip:101@5193031_vats>;tag=6p4X3UKB4D6FK
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Call-ID: 80e474f9-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] CSeq: 71636927 BYE
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Contact: <sip:102@192.168.99.12:5060>
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Max-Forwards: 70
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] User-Agent: TS-v4.5.1-18fW
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Reason: MVTSLocal;cause=10;text="[SIP] BYE received"
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] Content-Length: 0
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip]
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.816[sip] ------------------------------------------------------------------------
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.816[app:dbg]got nua_i_bye : 100(Trying)
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip]send 719 bytes to udp/[192.168.99.12]:5060 at 20:33:30.815433:
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] ------------------------------------------------------------------------
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] SIP/2.0 200 OK
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Via: SIP/2.0/UDP 192.168.99.12:5060;rport=5060;branch=z9hG4bK-975337220-3826354356-731956899-31610715441
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Via: SIP/2.0/UDP 192.168.99.12:5063;rport=5063;branch=z9hG4bK-975337220-3826354356-731956899-3161071544;received=192.168.99.12
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] From: <sip:102@5193031_vats>;tag=1926960881-3826354100-731956899-3161071544
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] To: "101" <sip:101@5193031_vats>;tag=6p4X3UKB4D6FK
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Call-ID: 80e474f9-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] CSeq: 71636927 BYE
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] User-Agent: TAU-8.IP/1.9.0 SN/VI20000058 sofia-sip/1.12.10
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, INFO
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Supported: 100rel, replaces
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] Content-Length: 0
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] P-RTP-Stat: PS=1506, OS=259032, PR=1516, OR=260752, PL=0, JI=20
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip]
Feb 14 23:33:30 OpenWrt user.notice syslog: 23:33:30.826[sip] ------------------------------------------------------------------------
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]got nua_i_state : 200(Session Terminated)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]NO SIP IN nua_i_state == 200 : Session Terminated
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]self_i_state(): call state 10: : : sdp_init have_oc
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]sip: call 0006000a: terminated
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]6937: endpoint 6 set to busy
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]sip -[msg_clear]-> pbx
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.826[app:dbg]self_callstate_terminated: call id = 0006000A need_exchange_at_answer = 0
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]ITC: [msg_clear] -> pbx
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]dump_port_calls() SLIC 6:
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Q:(0x31c800,0x0006000A,(nil))
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: peer cleared(0006000A)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: current call cleared
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: -> busy - no hold call, no wait call
Feb 14 23:33:30 OpenWrt user.info syslog: 23:33:30.836[app:info]SLIC 6: from state 'talking' to state 'busy'
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]port_start_tone(6 22 0 0)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]CMD_START_TONE: port = 6
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Port 6: check vapi queue ('free') at vapi_start_tone_chan:1263
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Chan 6: current state is CREATED
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Port 6: set vapi queue MSP to 'busy' with 'start_tone' at vapi_start_tone_chan:1300
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]VQ Conn 6 = MSP : 'start_tone' =
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]chan 6 start tone, id=22, direction=TDM
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Port 6: user port 0, old state busy, new state
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Set port 6 led to state 'LED_ON'
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]pbx -[msg_fxs_state]-> group
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: connection STATISTIC
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: Rx_pack = 1571
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: Rx_oct = 270212
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: Lost_pack = 0
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: Tx_pack = 1560
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: Tx_oct = 268320
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi: chan 6: peak_jiter = 20
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Common port statistic
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Rx_pack = 20748
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Rx_oct = 3568497
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Lost_pack = 0
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Tx_pack = 19361
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: Tx_oct = 3304439
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: peak_jiter = 20
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]SLIC 6: reset call 0x0006000A (active)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]CMD_SET_VOICE: port = 6
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Port 6: check vapi queue ('busy''start_tone') at vapi_start_stop_chan:1502
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Port 6 put cmd 'set voice',cur 'start_tone' to queue at (vapi_start_stop_chan:1509)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]VQ Conn 6 = MSP : 'start_tone' =
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]VQ Conn 6 + 04 : 'set voice' + <-get_ptr
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]dump_port_calls() SLIC 6:
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]Q:NONE
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x00000922
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.836[app:dbg]ITC: [msg_fxs_state] -> group
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.846[app:dbg]-----[GM] self_fxs_state()
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.846[app:dbg]Port 6: new state is busy
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.846[app:dbg]Delete all RX-TX medias from SLIC 6
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.846[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x00000922 result 0x00000000
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Conn 6: Start tone - Successfull
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Port 6: check vapi queue ('busy''start_tone') at vapi_next_ops:2408
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Port 6 get cmd 'set voice' from queue at (vapi_next_ops:2427)
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Port 6: check vapi queue ('free') at vapi_start_stop_chan:1502
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Chan 6: current state is CREATED
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]vapi: Conn 6. start_stop voice chan, TX stop, RX stop
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]Port 6: set vapi queue MSP to 'busy' with 'set voice' at vapi_start_stop_chan:1545
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]VQ Conn 6 = MSP : 'set voice' =
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.856[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x00000401
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.866[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.866[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x00000401 result 0x00000000
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.866[app:dbg]vapi: conn 6. RTCP disabled
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.866[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x000004ff
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x000004ff result 0x00000000
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]Conn 6: Set voice mode successeful
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]Stop all medias on chan 6
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]Port 6: check vapi queue ('busy''set voice') at vapi_next_ops:2408
Feb 14 23:33:30 OpenWrt user.debug syslog: 23:33:30.876[app:dbg]Mute all RX-TX medias on SLIC 6
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:31 OpenWrt user.warn syslog: 23:33:31.036[app:WARN]-1 is not profile id, return settings from profile 0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip]send 705 bytes to udp/[192.168.99.12]:5060 at 20:33:38.615433:
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] REGISTER sip:5193031_vats SIP/2.0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Via: SIP/2.0/UDP 192.168.99.77;rport;branch=z9hG4bKccmvjg0erZK5e
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Max-Forwards: 70
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] To: <sip:101@5193031_vats>
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Call-ID: 64afff79-2f2b-1233-f082-a8f94b02b1da
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] CSeq: 71636903 REGISTER
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Contact: <sip:101@192.168.99.77:5060>
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Expires: 1800
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] User-Agent: TAU-8.IP/1.9.0 SN/VI20000058 sofia-sip/1.12.10
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, INFO
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Supported: timer, 100rel, replaces, path
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Authorization: Digest username="101", realm="SIP-REGISTRAR", nonce="830B580E", algorithm=MD5, uri="sip:5193031_vats", response="1ad6d783cabc4d0e9051ba4cd57de501"
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Content-Length: 0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip]
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip]recv 410 bytes from udp/[192.168.99.12]:5060 at 20:33:38.615433:
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] SIP/2.0 200 Ok
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Via: SIP/2.0/UDP 192.168.99.77:5060;rport=5060;branch=z9hG4bKccmvjg0erZK5e;received=192.168.99.77
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] To: <sip:101@5193031_vats>;tag=091e351cb48811e4a3c6a02bb81f6abc
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Call-ID: 64afff79-2f2b-1233-f082-a8f94b02b1da
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] CSeq: 71636903 REGISTER
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.616[sip] Contact: <sip:101@192.168.99.77:5060>;expires=120
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] Expires: 120
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] Server: TS-v4.5.1-18fW
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] Content-Length: 0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip]
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip]send 367 bytes to udp/[192.168.99.12]:5060 at 20:33:38.625433:
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] NOTIFY sip:101@5193031_vats SIP/2.0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] Via: SIP/2.0/UDP 192.168.99.77;rport;branch=z9hG4bKDNDNmBHjN89Qa
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.626[sip] Max-Forwards: 70
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] To: <sip:101@5193031_vats>
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] Call-ID: 99116bfa-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] CSeq: 71636902 NOTIFY
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] User-Agent: TAU-8.IP/1.9.0 SN/VI20000058 sofia-sip/1.12.10
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] Event: keep-alive
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] Content-Length: 0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip]
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.636[app:dbg]got nua_r_register : 200(Ok)
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.636[app:dbg]sip: endpoint 6: REGISTER: 200 Ok
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.636[app:dbg]Port 6: user port 0, old state busy, new state
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.636[app:dbg]sip: endpoint 6: successfully registered
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip]recv 333 bytes from udp/[192.168.99.12]:5060 at 20:33:38.635433:
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] SIP/2.0 501 Method NOTIFY not implemented
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] Via: SIP/2.0/UDP 192.168.99.77:5060;rport=5060;branch=z9hG4bKDNDNmBHjN89Qa;received=192.168.99.77
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] From: <sip:101@5193031_vats>;tag=44Hc05H49USac
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] To: <sip:101@5193031_vats>
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.636[sip] Call-ID: 99116bfa-2f2b-1233-f182-a8f94b02b1da
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.646[sip] CSeq: 71636902 NOTIFY
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.646[sip] Server: TS-v4.5.1-18fW
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.646[sip] Content-Length: 0
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.646[sip]
Feb 14 23:33:38 OpenWrt user.notice syslog: 23:33:38.646[sip] ------------------------------------------------------------------------
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]slic6. Event 8.
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]slic 6. Pre-On-hook event
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]HIO: preonhook TDM port '6', port enabled 1
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]CMD_STOP_TONE: port = 6
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]Port 6: check vapi queue ('free') at vapi_stop_tone_chan:1353
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]Chan 6: current state is CREATED
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]Port 6: set vapi queue MSP to 'busy' with 'stop_tone' at vapi_stop_tone_chan:1399
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]VQ Conn 6 = MSP : 'stop_tone' =
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]chan 6 stop tone
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.796[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x00000923
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.806[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.806[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x00000923 result 0x00000000
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.806[app:dbg]Conn 6: Stop tone - Successfull
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.806[app:dbg]Port 6: check vapi queue ('busy''stop_tone') at vapi_next_ops:2408
Feb 14 23:33:38 OpenWrt user.debug syslog: 23:33:38.816[app:dbg]vapi: generic event, code 2 <Tone Generation completed>, conn 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]slic6. Event 1.
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]slic 6. On-hook event
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Set port 6 led to state 'LED_OFF'
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]HIO: onhook TDM port '6', port enabled 1
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]SLIC 6 (101): onhook state: busy
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]regex ID 6: dial reset
Feb 14 23:33:39 OpenWrt user.info syslog: 23:33:39.296[app:info]SLIC 6: from state 'busy' to state 'hangup'
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]CMD_STOP_TONE: port = 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: check vapi queue ('free') at vapi_stop_tone_chan:1353
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Chan 6: current state is CREATED
Feb 14 23:33:39 OpenWrt user.err syslog: 23:33:39.296[app:ERR]chan 6: no generated tones!
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]vapi_chan.c:1389: conn 6 peek cmd 'no event' from queue
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: check vapi queue ('free') at __cmd_engine:302
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: check vapi queue ('free') at vapi_next_ops:2408
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]SLIC 6: reset
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]dump_port_calls() SLIC 6:
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Q:NONE
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]free_final_mx: final_mx was NULL for SLIC 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]CMD_DESTROY_CONN: port = 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: check vapi queue ('free') at vapi_destroy_chan:769
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Destroying connection 6...
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Chan 6: current state is CREATED
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: set vapi queue MSP to 'busy' with 'destroy' at vapi_destroy_chan:795
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]VQ Conn 6 = MSP : 'destroy' =
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Chan 6: CREATED -> DESTROYING
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]CMD_DESTROY_IPONLY_CONN: port = 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: check vapi queue ('busy''destroy') at vapi_destroy_chan:769
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Clear vapi queue of Port 6/chan 14
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6 put cmd 'destroy',cur 'destroy' to queue at (vapi_destroy_chan:776)
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]VQ Conn 14 = MSP : 'destroy' =
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]VQ Conn 14 + 00 : 'destroy' (hold) + <-get_ptr
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Port 6: user port 0, old state hangup, new state
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Set port 6 led to state 'LED_OFF'
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]pbx -[msg_fxs_state]-> group
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]dump_port_calls() SLIC 6:
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Q:NONE
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Set port 6 led to state 'LED_OFF'
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Delete all RX-TX medias from SLIC 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Delete all RX-TX medias from SLIC 14
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]Delete all RX-TX medias from SLIC 6
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.306[app:dbg]ITC: [msg_fxs_state] -> group
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.306[app:dbg]-----[GM] self_fxs_state()
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.306[app:dbg]Port 6: new state is hangup
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.296[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x0000014b
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.306[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.306[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x0000014b result 0x00000000
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]vapi_cb_req: 6 0 result 0x00000000 requid 0x00000103
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]vapi_proc_event: VAPI_CB
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]vapi_cb_chan: chan 6 reqest_id 0x00000103 result 0x00000000
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Conn 6 destroyed
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Chan 6: DESTROYING -> INITIAL
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Port 6: check vapi queue ('busy''destroy') at vapi_next_ops:2408
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Port 6 get cmd 'destroy' from queue at (vapi_next_ops:2427)
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Port 6: check vapi queue ('free') at vapi_destroy_chan:769
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Destroying connection 14...
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Chan 14: current state is INITIAL
Feb 14 23:33:39 OpenWrt user.err syslog: 23:33:39.316[app:ERR]vapi_destroy_chan() chan 14: current state initial: duplicate destroying connection!
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Port 6: check vapi queue ('free') at vapi_next_ops:2570
Feb 14 23:33:39 OpenWrt user.debug syslog: 23:33:39.316[app:dbg]Port 6: check vapi queue ('free') at vapi_next_ops:2408