2013-02-06T21:06:27.190384-06:00opensips opensips[1027]: DBG:core:parse_msg: SIP Request:
2013-02-06T21:06:27.190415-06:00opensips opensips[1027]: DBG:core:parse_msg:  method:  <INVITE>
2013-02-06T21:06:27.190424-06:00opensips opensips[1027]: DBG:core:parse_msg:  uri:     <sip:3428201092075169@5.104.224.33:5060>
2013-02-06T21:06:27.190430-06:00opensips opensips[1027]: DBG:core:parse_msg:  version: <SIP/2.0>
2013-02-06T21:06:27.190435-06:00opensips opensips[1027]: DBG:core:parse_headers: flags=2
2013-02-06T21:06:27.190440-06:00opensips opensips[1027]: DBG:core:get_hdr_field: cseq <CSeq>: <1> <INVITE>
2013-02-06T21:06:27.190445-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<1 INVITE>
2013-02-06T21:06:27.190451-06:00opensips opensips[1027]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK141941104132388220996211>; state=16
2013-02-06T21:06:27.190456-06:00opensips opensips[1027]: DBG:core:parse_via: end of header reached, state=5
2013-02-06T21:06:27.190460-06:00opensips opensips[1027]: DBG:core:parse_headers: via found, flags=2
2013-02-06T21:06:27.190465-06:00opensips opensips[1027]: DBG:core:parse_headers: this is the first via
2013-02-06T21:06:27.190471-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 89.207.133.93:5060;branch=z9hG4bK141941104132388220996211>
2013-02-06T21:06:27.190678-06:00opensips opensips[1027]: DBG:core:parse_msg:  first  via: <SIP/2.0/UDP> <89.207.133.93:5060(5060)>
2013-02-06T21:06:27.190688-06:00opensips opensips[1027]: DBG:core:parse_msg: ;<branch=z9hG4bK141941104132388220996211>
2013-02-06T21:06:27.190694-06:00opensips opensips[1027]: DBG:core:parse_msg: 
2013-02-06T21:06:27.190705-06:00opensips opensips[1027]: DBG:core:parse_msg: exiting
2013-02-06T21:06:27.190712-06:00opensips opensips[1027]: DBG:core:receive_msg: After parse_msg...
2013-02-06T21:06:27.190717-06:00opensips opensips[1027]: DBG:core:receive_msg: preparing to run routing scripts...
2013-02-06T21:06:27.190723-06:00opensips opensips[1027]: DBG:core:parse_headers: flags=8
2013-02-06T21:06:27.190729-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 4, name=<From>, body=<<sip:962779330138@89.207.133.93:5060>;tag=191441220996211>
2013-02-06T21:06:27.190736-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<28dd2c2273@89.207.133.93>
2013-02-06T21:06:27.190751-06:00opensips opensips[1027]: DBG:core:parse_to: end of header reached, state=10
2013-02-06T21:06:27.190758-06:00opensips opensips[1027]: DBG:core:parse_to: display={}, ruri={sip:3428201092075169@5.104.224.33:5060}
2013-02-06T21:06:27.190764-06:00opensips opensips[1027]: DBG:core:get_hdr_field: <To> [42]; uri=[sip:3428201092075169@5.104.224.33:5060] 
2013-02-06T21:06:27.190770-06:00opensips opensips[1027]: DBG:core:get_hdr_field: to body [<sip:3428201092075169@5.104.224.33:5060>#015#012]
2013-02-06T21:06:27.190776-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 3, name=<To>, body=<<sip:3428201092075169@5.104.224.33:5060>>
2013-02-06T21:06:27.190787-06:00opensips opensips[1027]: DBG:core:parse_headers: flags=100
2013-02-06T21:06:27.190794-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 7, name=<Contact>, body=<<sip:89.207.133.93:5060;transport=udp>>
2013-02-06T21:06:27.190801-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 20, name=<Allow>, body=<INVITE, ACK, OPTIONS, BYE, CANCEL, REGISTER, INFO, NOTIFY, MESSAGE, SUBSCRIBE, REFER, PUBLISH, UPDATE>
2013-02-06T21:06:27.190807-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 12, name=<Content-Type>, body=<application/sdp>
2013-02-06T21:06:27.190814-06:00opensips opensips[1027]: DBG:core:get_hdr_field: content_length=232
2013-02-06T21:06:27.190820-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 13, name=<Content-Length>, body=<232>
2013-02-06T21:06:27.190825-06:00opensips opensips[1027]: DBG:core:parse_headers: header field type 8, name=<Max-Forwards>, body=<70>
2013-02-06T21:06:27.190830-06:00opensips opensips[1027]: DBG:maxfwd:is_maxfwd_present: value = 70 
2013-02-06T21:06:27.190859-06:00opensips opensips[1027]: DBG:core:check_ip_address: params 89.207.133.93, 89.207.133.93, 0
2013-02-06T21:06:27.190866-06:00opensips opensips[1027]: DBG:core:parse_headers: flags=ffffffffffffffff
2013-02-06T21:06:27.190876-06:00opensips opensips[1027]: DBG:core:get_hdr_field: found end of header
2013-02-06T21:06:27.190893-06:00opensips opensips[1027]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<>(0)#012 passwd=<>(0)#012 host=<89.207.133.93>(13)#012 port=<5060>(4): 5060#012 params=<transport=udp>(13)#012 headers=<>(0)
2013-02-06T21:06:27.190900-06:00opensips opensips[1027]: DBG:core:parse_uri:  uri params:#012   transport=<transport=udp>, val=<udp>, proto=1
2013-02-06T21:06:27.190907-06:00opensips opensips[1027]: DBG:core:parse_uri:    user-param=<>, val=<>
2013-02-06T21:06:27.190912-06:00opensips opensips[1027]: DBG:core:parse_uri:    method=<>, val=<>
2013-02-06T21:06:27.190919-06:00opensips opensips[1027]: DBG:core:parse_uri:    ttl=<>, val=<>
2013-02-06T21:06:27.190924-06:00opensips opensips[1027]: DBG:core:parse_uri:    maddr=<>, val=<>
2013-02-06T21:06:27.190930-06:00opensips opensips[1027]: DBG:core:parse_uri:    lr=<>, val=<>
2013-02-06T21:06:27.190936-06:00opensips opensips[1027]: DBG:core:parse_uri:    r2=<>, val=<>
2013-02-06T21:06:27.190948-06:00opensips opensips[1027]: DBG:uri:has_totag: no totag
2013-02-06T21:06:27.190964-06:00opensips opensips[1027]: DBG:core:parse_headers: flags=ffffffffffffffff
2013-02-06T21:06:27.190994-06:00opensips opensips[1027]: DBG:sl:run_sl_callbacks: callback id 1 entered
2013-02-06T21:06:27.191001-06:00opensips opensips[1027]: DBG:siptrace:trace_sl_onreply_out: trace off...
2013-02-06T21:06:27.191007-06:00opensips opensips[1027]: DBG:sl:run_sl_callbacks: callback id 0 entered
2013-02-06T21:06:27.191051-06:00opensips opensips[1027]: DBG:core:destroy_avp_list: destroying list 0x7fa79c583a48
2013-02-06T21:06:27.191060-06:00opensips opensips[1027]: DBG:core:receive_msg: cleaning up
2013-02-06T21:06:27.195711-06:00opensips opensips[1029]: DBG:core:parse_msg: SIP Request:
2013-02-06T21:06:27.195724-06:00opensips opensips[1029]: DBG:core:parse_msg:  method:  <ACK>
2013-02-06T21:06:27.195730-06:00opensips opensips[1029]: DBG:core:parse_msg:  uri:     <sip:3428201092075169@5.104.224.33:5060>
2013-02-06T21:06:27.195736-06:00opensips opensips[1029]: DBG:core:parse_msg:  version: <SIP/2.0>
2013-02-06T21:06:27.195742-06:00opensips opensips[1029]: DBG:core:parse_headers: flags=2
2013-02-06T21:06:27.195748-06:00opensips opensips[1029]: DBG:core:get_hdr_field: cseq <CSeq>: <1> <ACK>
2013-02-06T21:06:27.195754-06:00opensips opensips[1029]: DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<1 ACK>
2013-02-06T21:06:27.195760-06:00opensips opensips[1029]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK141941104132388220996211>; state=16
2013-02-06T21:06:27.195766-06:00opensips opensips[1029]: DBG:core:parse_via: end of header reached, state=5
2013-02-06T21:06:27.195771-06:00opensips opensips[1029]: DBG:core:parse_headers: via found, flags=2
2013-02-06T21:06:27.195777-06:00opensips opensips[1029]: DBG:core:parse_headers: this is the first via
2013-02-06T21:06:27.195783-06:00opensips opensips[1029]: DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 89.207.133.93:5060;branch=z9hG4bK141941104132388220996211>
2013-02-06T21:06:27.195790-06:00opensips opensips[1029]: DBG:core:parse_msg:  first  via: <SIP/2.0/UDP> <89.207.133.93:5060(5060)>
2013-02-06T21:06:27.195796-06:00opensips opensips[1029]: DBG:core:parse_msg: ;<branch=z9hG4bK141941104132388220996211>
2013-02-06T21:06:27.195802-06:00opensips opensips[1029]: DBG:core:parse_msg: 
2013-02-06T21:06:27.195808-06:00opensips opensips[1029]: DBG:core:parse_msg: exiting
2013-02-06T21:06:27.195814-06:00opensips opensips[1029]: DBG:core:receive_msg: After parse_msg...
2013-02-06T21:06:27.195820-06:00opensips opensips[1029]: DBG:core:receive_msg: preparing to run routing scripts...
2013-02-06T21:06:27.195826-06:00opensips opensips[1029]: DBG:core:parse_headers: flags=8
2013-02-06T21:06:27.195833-06:00opensips opensips[1029]: DBG:core:parse_headers: header field type 4, name=<From>, body=<<sip:962779330138@89.207.133.93:5060>;tag=191441220996211>
2013-02-06T21:06:27.195839-06:00opensips opensips[1029]: DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<28dd2c2273@89.207.133.93>
2013-02-06T21:06:27.195845-06:00opensips opensips[1029]: DBG:core:parse_to_param: tag=e301a115facbf1bb55a21cb487f36cb6.2bff
2013-02-06T21:06:27.195851-06:00opensips opensips[1029]: DBG:core:parse_to: end of header reached, state=29
2013-02-06T21:06:27.195858-06:00opensips opensips[1029]: DBG:core:parse_to: display={}, ruri={sip:3428201092075169@5.104.224.33:5060}
2013-02-06T21:06:27.195865-06:00opensips opensips[1029]: DBG:core:get_hdr_field: <To> [84]; uri=[sip:3428201092075169@5.104.224.33:5060] 
2013-02-06T21:06:27.195872-06:00opensips opensips[1029]: DBG:core:get_hdr_field: to body [<sip:3428201092075169@5.104.224.33:5060>]
2013-02-06T21:06:27.195878-06:00opensips opensips[1029]: DBG:core:parse_headers: header field type 3, name=<To>, body=<<sip:3428201092075169@5.104.224.33:5060>;tag=e301a115facbf1bb55a21cb487f36cb6.2bff>
2013-02-06T21:06:27.195884-06:00opensips opensips[1029]: DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
2013-02-06T21:06:27.195890-06:00opensips opensips[1029]: DBG:core:destroy_avp_list: destroying list (nil)
2013-02-06T21:06:27.195895-06:00opensips opensips[1029]: DBG:core:receive_msg: cleaning up


2013-02-06T21:06:28.308618-06:00opensips opensips[1072]: DBG:db_virtual:reconnect_timer: reconnect with timer
^C