16:20:09.557[sip]nta: sent OPTIONS (3768) to */192.168.0.101:5060
16:20:09.577[sip]recv 287 bytes from udp/[192.168.0.101]:5060 at 02:49:22.230000:
16:20:09.577[sip]   ------------------------------------------------------------------------
16:20:09.577[sip]   SIP/2.0 501 Not Implemented
16:20:09.577[sip]   Via: SIP/2.0/UDP 192.168.0.217;branch=z9hG4bKBcc4HFZNaQgHB;rport=5060
16:20:09.577[sip]   To: "217" <sip:217@192.168.0.101>;tag=4371
16:20:09.577[sip]   From: "217" <sip:217@192.168.0.101>;tag=B455cpQ6pNXDp
16:20:09.577[sip]   Call-ID: 252428fd-dfd8-1234-72b8-a8f94b0e6be9
16:20:09.577[sip]   CSeq: 3768 OPTIONS
16:20:09.577[sip]   Content-Length: 0
16:20:09.577[sip]   
16:20:09.577[sip]   ------------------------------------------------------------------------
16:20:09.577[sip]nta: received 501 Not Implemented for OPTIONS (3768)
16:20:09.577[sip]nta: 501 Not Implemented is going to a transaction
16:20:10.357[sip]send 381 bytes to udp/[192.168.0.101]:5060 at 02:49:23.010000:
16:20:10.357[sip]   ------------------------------------------------------------------------
16:20:10.357[sip]   OPTIONS sip:220@192.168.0.101 SIP/2.0
16:20:10.357[sip]   Via: SIP/2.0/UDP 192.168.0.217;rport;branch=z9hG4bKcN5vKagS7Z63p
16:20:10.357[sip]   Max-Forwards: 70
16:20:10.357[sip]   From: "220" <sip:220@192.168.0.101>;tag=7Z0259Kr2H42Q
16:20:10.357[sip]   To: "220" <sip:220@192.168.0.101>
16:20:10.357[sip]   Call-ID: 5b4f58fe-dfd8-1234-72b8-a8f94b0e6be9
16:20:10.357[sip]   CSeq: 3770 OPTIONS
16:20:10.357[sip]   Subject: KEEPALIVE
16:20:10.357[sip]   User-Agent: TAU-8.IP/2.1.0 SN/VI33023837 sofia-sip/1.12.10
16:20:10.357[sip]   Content-Length: 0
16:20:10.357[sip]   
16:20:10.357[sip]   ------------------------------------------------------------------------
16:20:10.357[sip]nta: sent OPTIONS (3770) to */192.168.0.101:5060
16:20:10.377[sip]recv 288 bytes from udp/[192.168.0.101]:5060 at 02:49:23.030000:
16:20:10.377[sip]   ------------------------------------------------------------------------
16:20:10.377[sip]   SIP/2.0 501 Not Implemented
16:20:10.377[sip]   Via: SIP/2.0/UDP 192.168.0.217;branch=z9hG4bKcN5vKagS7Z63p;rport=5060
16:20:10.377[sip]   To: "220" <sip:220@192.168.0.101>;tag=15186
16:20:10.377[sip]   From: "220" <sip:220@192.168.0.101>;tag=7Z0259Kr2H42Q
16:20:10.377[sip]   Call-ID: 5b4f58fe-dfd8-1234-72b8-a8f94b0e6be9
16:20:10.377[sip]   CSeq: 3770 OPTIONS
16:20:10.377[sip]   Content-Length: 0
16:20:10.377[sip]   
16:20:10.377[sip]   ------------------------------------------------------------------------
16:20:10.377[sip]nta: received 501 Not Implemented for OPTIONS (3770)
16:20:10.377[sip]nta: 501 Not Implemented is going to a transaction
16:20:11.847[app:dbg]slic4. Event 8.
16:20:11.847[app:dbg]slic 4. Pre-On-hook event
16:20:11.847[app:dbg]HIO: preonhook TDM port '4', port enabled 1
16:20:11.847[app:dbg]CMD_STOP_TONE: port = 4
16:20:11.847[app:dbg]Port 4: check vapi queue ('free') at vapi_stop_tone_chan:1503
16:20:11.847[app:dbg]Chan 4: current state is CREATED
16:20:11.847[app:ERR]chan 4: no generated tones!
16:20:11.847[app:dbg]vapi_chan.c:1539: conn 4 peek cmd 'no event' from queue
16:20:11.847[app:dbg]Port 4: check vapi queue ('free') at __cmd_engine:321
16:20:11.847[app:dbg]Port 4: check vapi queue ('free') at vapi_next_ops:2599
16:20:11.937[app:dbg]slic4. Event 5.
16:20:11.937[app:info]SLIC 4: flash
16:20:11.937[app:dbg]dump_port_calls() SLIC 4:
16:20:11.937[app:dbg]Q:(0x364400,0x02040001,(nil))
16:20:11.937[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
16:20:11.937[app:dbg]OnFlash: transfer (218)
16:20:11.937[app:info]SLIC 4: Transmit flash
16:20:11.937[app:dbg]CMD_GEN_2833: port = 4
16:20:11.937[app:dbg]Port 4: check vapi queue ('free') at vapi_generate_dtmf_2833:2372
16:20:11.937[app:dbg]Chan 4: current state is CREATED
16:20:11.937[app:dbg]Port 4: set vapi queue MSP to 'busy' with 'start_2833' at vapi_generate_dtmf_2833:2396
16:20:11.937[app:dbg]VQ Conn 4 = MSP :    'start_2833' =
16:20:11.937[app:dbg]conn 4. Send NTE event 16
16:20:11.937[app:dbg]vapi: chan '4' send RFC2833 event '16' start PT override '0' PT '101' to RTP destination
16:20:11.937[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x00000a31
16:20:11.937[app:dbg]vapi_proc_event: VAPI_CB
16:20:11.937[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x00000a31 result 0x00000000
16:20:11.937[app:dbg]Conn 4. Cmd 'Send NTE event' successfully completed
16:20:11.937[app:dbg]Port 4: check vapi queue ('busy''start_2833') at vapi_next_ops:2599
16:20:11.997[app:dbg]vapi_proc_event: eVAPI_NTE_TRANSMIT_COMPLETE_EVENT
16:20:11.997[app:dbg]Port 4: check vapi queue ('free') at vapi_generate_stop_dtmf_2833:2455
16:20:11.997[app:dbg]Chan 4: current state is CREATED
16:20:11.997[app:dbg]Port 4: set vapi queue MSP to 'busy' with 'stop_2833' at vapi_generate_stop_dtmf_2833:2478
16:20:11.997[app:dbg]VQ Conn 4 = MSP :     'stop_2833' =
16:20:11.997[app:dbg]vapi: chan '4' send RFC2833 event '16' stop PT override '0' PT '101' to RTP destination
16:20:11.997[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x00000a32
16:20:11.997[app:dbg]vapi_proc_event: VAPI_CB
16:20:11.997[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x00000a32 result 0x00000000
16:20:11.997[app:dbg]Conn 4. Cmd 'Send NTE event' successfully stopped
16:20:11.997[app:dbg]Port 4: check vapi queue ('busy''stop_2833') at vapi_next_ops:2599
16:20:12.057[app:dbg]vapi_proc_event: eVAPI_NTE_TRANSMIT_COMPLETE_EVENT
16:20:12.057[app:ERR]no port for connection 4
16:20:14.587[sip]nta: timer K fired, terminate OPTIONS (3768)
16:20:14.587[sip]nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
16:20:15.387[sip]nta: timer K fired, terminate OPTIONS (3770)
16:20:15.387[sip]nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
16:20:17.067[app:dbg]slic4. Event 8.
16:20:17.067[app:dbg]slic 4. Pre-On-hook event
16:20:17.067[app:dbg]HIO: preonhook TDM port '4', port enabled 1
16:20:17.067[app:dbg]CMD_STOP_TONE: port = 4
16:20:17.067[app:dbg]Port 4: check vapi queue ('free') at vapi_stop_tone_chan:1503
16:20:17.067[app:dbg]Chan 4: current state is CREATED
16:20:17.067[app:ERR]chan 4: no generated tones!
16:20:17.067[app:dbg]vapi_chan.c:1539: conn 4 peek cmd 'no event' from queue
16:20:17.067[app:dbg]Port 4: check vapi queue ('free') at __cmd_engine:321
16:20:17.067[app:dbg]Port 4: check vapi queue ('free') at vapi_next_ops:2599
16:20:17.967[app:dbg]slic4. Event 1.
16:20:17.967[app:dbg]slic 4. On-hook event
16:20:17.967[app:dbg]Set port 4 led to state 'LED_OFF'
16:20:17.967[app:dbg]HIO: onhook TDM port '4', port enabled 1
16:20:17.967[app:dbg]SLIC 4 (218): onhook state: talking
16:20:17.967[app:dbg]regex ID 4: dial reset
16:20:17.967[app:dbg]pbx -[msg_clear]-> sip
16:20:17.967[app:info]SLIC 4: from state 'talking' to state 'hangup'
16:20:17.967[app:dbg]CMD_STOP_TONE: port = 4
16:20:17.967[app:dbg]Port 4: check vapi queue ('free') at vapi_stop_tone_chan:1503
16:20:17.967[app:dbg]Chan 4: current state is CREATED
16:20:17.967[app:ERR]chan 4: no generated tones!
16:20:17.967[app:dbg]vapi_chan.c:1539: conn 4 peek cmd 'no event' from queue
16:20:17.967[app:dbg]Port 4: check vapi queue ('free') at __cmd_engine:321
16:20:17.967[app:dbg]Port 4: check vapi queue ('free') at vapi_next_ops:2599
16:20:17.967[app:dbg]SLIC 4: reset
16:20:17.967[app:dbg]dump_port_calls() SLIC 4:
16:20:17.967[app:dbg]Q:(0x364400,0x02040001,(nil))
16:20:17.967[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
16:20:17.967[app:dbg]vapi: chan 4: connection STATISTIC
16:20:17.967[app:dbg]vapi: chan 4: Rx_pack = 638
16:20:17.967[app:dbg]vapi: chan 4: Rx_oct  = 109736
16:20:17.967[app:dbg]vapi: chan 4: Lost_pack  = 0
16:20:17.967[app:dbg]vapi: chan 4: Tx_pack = 536
16:20:17.967[app:dbg]vapi: chan 4: Tx_oct  = 85214
16:20:17.967[app:dbg]vapi: chan 4: peak_jiter = 10
16:20:17.967[app:dbg]SLIC 4: Common port statistic
16:20:17.967[app:dbg]SLIC 4: Rx_pack = 5906
16:20:17.967[app:dbg]SLIC 4: Rx_oct  = 1005497
16:20:17.967[app:dbg]SLIC 4: Lost_pack  = 0
16:20:17.967[app:dbg]SLIC 4: Tx_pack = 5898
16:20:17.967[app:dbg]SLIC 4: Tx_oct  = 962163
16:20:17.967[app:dbg]SLIC 4: peak_jiter = 10
16:20:17.967[app:dbg]SLIC 4: reset call 0x02040001 (active)
16:20:17.967[app:dbg]CMD_SET_VOICE: port = 4
16:20:17.967[app:dbg]Port 4: check vapi queue ('free') at vapi_start_stop_chan:1652
16:20:17.967[app:dbg]Chan 4: current state is CREATED
16:20:17.967[app:dbg]vapi: Conn 4. start_stop voice chan, TX stop, RX stop
16:20:17.967[app:dbg]Port 4: set vapi queue MSP to 'busy' with 'set voice' at vapi_start_stop_chan:1695
16:20:17.967[app:dbg]VQ Conn 4 = MSP :     'set voice' =
16:20:17.967[app:dbg]incom_calls_set_media_started() call 0x02040001, group -1, task <sip> media stopped
16:20:17.967[app:dbg]incom_calls_rem() rem call 0x02040001, group -1, task <sip> from list
16:20:17.967[app:dbg]free_final_mx: final_mx was NULL for SLIC 4
16:20:17.967[app:dbg]CMD_DESTROY_CONN: port = 4
16:20:17.967[app:dbg]Port 4: check vapi queue ('busy''set voice') at vapi_destroy_chan:895
16:20:17.967[app:dbg]Clear vapi queue of Port 4/chan 4
16:20:17.967[app:dbg]Port 4 put cmd 'destroy',cur 'set voice' to queue at (vapi_destroy_chan:902)
16:20:17.967[app:dbg]VQ Conn 4 = MSP :     'set voice' =
16:20:17.967[app:dbg]VQ Conn 4 + 00  :       'destroy'  + <-get_ptr 
16:20:17.967[app:dbg]CMD_DESTROY_IPONLY_CONN: port = 4
16:20:17.967[app:dbg]Port 4: check vapi queue ('busy''set voice') at vapi_destroy_chan:895
16:20:17.967[app:dbg]Clear vapi queue of Port 4/chan 12
16:20:17.967[app:dbg]Port 4 put cmd 'destroy',cur 'set voice' to queue at (vapi_destroy_chan:902)
16:20:17.967[app:dbg]VQ Conn 12 = MSP :     'set voice' =
16:20:17.967[app:dbg]VQ Conn 12 + 00  :       'destroy'  + <-get_ptr 
16:20:17.967[app:dbg]VQ Conn 12 + 01  :       'destroy' (hold) +  
16:20:17.967[app:dbg]Port 4: user port 2, old state hangup, new state 
16:20:17.967[app:dbg]Set port 4 led to state 'LED_OFF'
16:20:17.967[app:dbg]pbx -[msg_fxs_state]-> group
16:20:17.967[app:dbg]dump_port_calls() SLIC 4:
16:20:17.967[app:dbg]Q:NONE
16:20:17.967[app:dbg]H:((nil),0x00000000,(nil),0) W:((nil),0x00000000,(nil)) N:((nil),0x00000000,(nil)) H2:((nil),0x00000000,(nil))
16:20:17.967[app:dbg]Set port 4 led to state 'LED_OFF'
16:20:17.967[app:dbg]Delete all RX-TX medias from SLIC 4
16:20:17.967[app:dbg]ITC: [msg_clear] -> sip
16:20:17.967[app:dbg]call 02040001,flags(00000048): endpoint 4 cleared
16:20:17.967[app:dbg]sip: call 02040001: BYE to sip:218@192.168.0.101
16:20:17.977[app:dbg]ITC: [msg_fxs_state] -> group
16:20:17.977[app:dbg]-----[GM] self_fxs_state()
16:20:17.977[app:dbg]Port 4: new state is hangup
16:20:17.977[sip]nua(0x302300): recv signal r_bye
16:20:17.977[sip]send 670 bytes to udp/[192.168.0.101]:5060 at 02:49:30.630000:
16:20:17.977[sip]   ------------------------------------------------------------------------
16:20:17.977[sip]   BYE sip:192.168.0.101:5060 SIP/2.0
16:20:17.977[sip]   Via: SIP/2.0/UDP 192.168.0.217;rport;branch=z9hG4bKDyyNN50v48vpj
16:20:17.977[sip]   Max-Forwards: 70
16:20:17.977[sip]   From: <sip:218@192.168.0.101>;tag=S2705g3QejNKr
16:20:17.977[sip]   To: "Zakurakin" <sip:333@192.168.0.101>;tag=18577
16:20:17.977[sip]   Call-ID: 00001dec-35218c5e0fad10009c6f0080f0a4b6a0@192.168.0.101
16:20:17.977[sip]   CSeq: 5085 BYE
16:20:17.977[sip]   Contact: <sip:218@192.168.0.217:5060>
16:20:17.977[sip]   User-Agent: TAU-8.IP/2.1.0 SN/VI33023837 sofia-sip/1.12.10
16:20:17.977[sip]   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, INFO
16:20:17.977[sip]   Supported: timer, 100rel, replaces
16:20:17.977[sip]   Reason: Q.850;cause=16;text="Normal call clearing"
16:20:17.977[sip]   Content-Length: 0
16:20:17.977[sip]   P-RTP-Stat: PS=536, OS=85214, PR=638, OR=109736, PL=0, JI=10
16:20:17.977[sip]   
16:20:17.977[sip]   ------------------------------------------------------------------------
16:20:17.977[sip]nta: sent BYE (5085) to */192.168.0.101:5060
16:20:17.977[app:dbg]Delete all RX-TX medias from SLIC 12
16:20:17.987[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x00000401
16:20:17.987[app:dbg]vapi_proc_event: VAPI_CB
16:20:17.987[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x00000401 result 0x00000000
16:20:17.987[app:dbg]vapi: conn 4. RTCP disabled
16:20:17.997[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x000004ff
16:20:17.997[app:dbg]vapi_proc_event: VAPI_CB
16:20:17.997[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x000004ff result 0x00000000
16:20:17.997[app:dbg]Conn 4: Set voice mode successeful
16:20:17.997[app:dbg]Stop all medias on chan 4
16:20:17.997[app:dbg]Port 4: check vapi queue ('busy''set voice') at vapi_next_ops:2599
16:20:17.997[app:dbg]Port 4 get cmd 'destroy' from queue at (vapi_next_ops:2618)
16:20:17.997[app:dbg]VQ Conn 4 + 01  :       'destroy' (hold) + <-get_ptr 
16:20:17.997[app:dbg]Port 4: check vapi queue ('free') at vapi_destroy_chan:895
16:20:17.997[app:dbg]Destroying connection 4...
16:20:17.997[app:dbg]Chan 4: current state is CREATED
16:20:17.997[app:dbg]Port 4: set vapi queue MSP to 'busy' with 'destroy' at vapi_destroy_chan:921
16:20:17.997[app:dbg]VQ Conn 4 = MSP :       'destroy' =
16:20:17.997[app:dbg]VQ Conn 4 + 01  :       'destroy' (hold) + <-get_ptr 
16:20:17.997[app:dbg]Chan 4: CREATED -> DESTROYING
16:20:17.997[app:dbg]Mute all RX-TX medias on SLIC 4
16:20:17.997[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x0000014b
16:20:17.997[app:dbg]vapi_proc_event: VAPI_CB
16:20:17.997[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x0000014b result 0x00000000
16:20:17.997[app:dbg]vapi_cb_req: 4 0 result 0x00000000 requid 0x00000103
16:20:17.997[app:dbg]vapi_proc_event: VAPI_CB
16:20:17.997[app:dbg]vapi_cb_chan: chan 4 reqest_id 0x00000103 result 0x00000000
16:20:17.997[app:dbg]Conn 4 destroyed
16:20:18.017[app:dbg]Chan 4: DESTROYING -> INITIAL
16:20:18.017[app:dbg]Port 4: check vapi queue ('busy''destroy') at vapi_next_ops:2599
16:20:18.017[app:dbg]Port 4 get cmd 'destroy' from queue at (vapi_next_ops:2618)
16:20:18.017[app:dbg]Port 4: check vapi queue ('free') at vapi_destroy_chan:895
16:20:18.017[app:dbg]Destroying connection 12...
16:20:18.017[app:dbg]Chan 12: current state is INITIAL
16:20:18.017[app:ERR]vapi_destroy_chan() chan 12: current state initial: duplicate destroying connection!
16:20:18.017[app:dbg]Port 4: check vapi queue ('free') at vapi_next_ops:2761
16:20:18.017[app:dbg]Port 4: check vapi queue ('free') at vapi_next_ops:2599
16:20:18.027[app:dbg]Delete all RX-TX medias from SLIC 4
16:20:18.027[sip]recv 288 bytes from udp/[192.168.0.101]:5060 at 02:49:30.660000:
16:20:18.027[sip]   ------------------------------------------------------------------------
16:20:18.027[sip]   SIP/2.0 200 OK
16:20:18.027[sip]   Via: SIP/2.0/UDP 192.168.0.217;branch=z9hG4bKDyyNN50v48vpj;rport=5060
16:20:18.027[sip]   To: "Zakurakin" <sip:333@192.168.0.101>;tag=18577
16:20:18.027[sip]   From: sip:218@192.168.0.101;tag=S2705g3QejNKr
16:20:18.027[sip]   Call-ID: 00001dec-35218c5e0fad10009c6f0080f0a4b6a0@192.168.0.101
16:20:18.027[sip]   CSeq: 5085 BYE
16:20:18.027[sip]   Content-Length: 0
16:20:18.027[sip]   
16:20:18.027[sip]   ------------------------------------------------------------------------
16:20:18.027[sip]nta: received 200 OK for BYE (5085)
16:20:18.027[sip]nta: 200 OK is going to a transaction
16:20:18.027[sip]nua(0x302300): event r_bye 200 OK
16:20:18.027[sip]nua(0x302300): call state changed: terminating -> terminated
16:20:18.027[sip]nua(0x302300): event i_state 200 to BYE
16:20:18.027[sip]nua(0x302300): event i_terminated 200 to BYE
16:20:18.027[sip]nua(0x302300): removing session usage
16:20:18.027[sip]nua: terminated session 0x302300
16:20:18.027[app:dbg]got nua_r_bye : 200(OK)
16:20:18.027[app:dbg]sip: call 02040001: BYE/INFO: 200 OK
16:20:18.027[app:dbg]got nua_i_state : 200(to BYE)
16:20:18.027[app:dbg]NO SIP IN nua_i_state == 200 : to BYE
16:20:18.027[app:dbg]self_i_state(): call state 10: : : sdp_init have_oc
16:20:18.027[app:dbg]sip: call 02040001: terminated
16:20:18.027[app:dbg]self_callstate_terminated: call id = 02040001 need_exchange_at_answer = 0
16:20:18.027[sip]nua(0x302300): recv signal r_destroy
16:20:19.447[sip]send 381 bytes to udp/[192.168.0.101]:5060 at 02:49:32.100000:
16:20:19.447[sip]   ------------------------------------------------------------------------
16:20:19.447[sip]   OPTIONS sip:223@192.168.0.101 SIP/2.0
16:20:19.447[sip]   Via: SIP/2.0/UDP 192.168.0.217;rport;branch=z9hG4bKe7QeQ0H01HK9D
16:20:19.447[sip]   Max-Forwards: 70
16:20:19.447[sip]   From: "223" <sip:223@192.168.0.101>;tag=5DeH2KjH8ZQXg
16:20:19.447[sip]   To: "223" <sip:223@192.168.0.101>
16:20:19.447[sip]   Call-ID: 60ba5f1d-dfd8-1234-72b8-a8f94b0e6be9
16:20:19.447[sip]   CSeq: 3771 OPTIONS
16:20:19.447[sip]   Subject: KEEPALIVE
16:20:19.447[sip]   User-Agent: TAU-8.IP/2.1.0 SN/VI33023837 sofia-sip/1.12.10
16:20:19.447[sip]   Content-Length: 0
16:20:19.447[sip]   
16:20:19.447[sip]   ------------------------------------------------------------------------
16:20:19.447[sip]nta: sent OPTIONS (3771) to */192.168.0.101:5060
16:20:19.467[sip]recv 287 bytes from udp/[192.168.0.101]:5060 at 02:49:32.120000:
16:20:19.467[sip]   ------------------------------------------------------------------------
16:20:19.467[sip]   SIP/2.0 501 Not Implemented
16:20:19.467[sip]   Via: SIP/2.0/UDP 192.168.0.217;branch=z9hG4bKe7QeQ0H01HK9D;rport=5060
16:20:19.467[sip]   To: "223" <sip:223@192.168.0.101>;tag=2058
16:20:19.467[sip]   From: "223" <sip:223@192.168.0.101>;tag=5DeH2KjH8ZQXg
16:20:19.467[sip]   Call-ID: 60ba5f1d-dfd8-1234-72b8-a8f94b0e6be9
16:20:19.467[sip]   CSeq: 3771 OPTIONS
16:20:19.467[sip]   Content-Length: 0
16:20:19.467[sip]   
16:20:19.467[sip]   ------------------------------------------------------------------------
16:20:19.467[sip]nta: received 501 Not Implemented for OPTIONS (3771)
16:20:19.467[sip]nta: 501 Not Implemented is going to a transaction
16:20:20.187[sip]send 753 bytes to udp/[192.168.0.101]:5060 at 02:49:32.840000:
16:20:20.187[sip]   ------------------------------------------------------------------------
16:20:20.187[sip]   REGISTER sip:192.168.0.101:5060 SIP/2.0
16:20:20.187[sip]   Via: SIP/2.0/UDP 192.168.0.217;rport;branch=z9hG4bKFgH7rU23yt9US
16:20:20.187[sip]   Max-Forwards: 70
16:20:20.187[sip]   From: "224" <sip:224@192.168.0.101>;tag=6p793e3m58Dgc
16:20:20.187[sip]   To: "224" <sip:224@192.168.0.101>
16:20:20.187[sip]   Call-ID: f4c2a89e-dfd1-1234-6cb8-a8f94b0e6be9
16:20:20.187[sip]   CSeq: 3681 REGISTER
16:20:20.187[sip]   Contact: <sip:224@192.168.0.217:5060>
16:20:20.187[sip]   Expires: 320
16:20:20.187[sip]   User-Agent: TAU-8.IP/2.1.0 SN/VI33023837 sofia-sip/1.12.10
16:20:20.187[sip]   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, INFO
16:20:20.187[sip]   Supported: timer, 100rel, replaces, path
16:20:20.187[sip]   Authorization: Digest username="224", realm="Registered Users", nonce="2b56ad5bb66cd8b061c3870e1d3b76ec", algorithm=MD5, uri="sip:192.168.0.101:5060", response="28362984484cec19b80d39b49ec1ccd3"
16:20:20.187[sip]   Content-Length: 0
16:20:20.187[sip]   
