 1(2262224) DEBUG: <core> [core/udp_server.c:494]: udp_rcv_loop(): received on udp socket: (106/100/493) [[INVITE sip:100@172.16.1.19:7060;user=phone SIP/2.0 0D  0A Via: SIP/2.0/UDP 172.16.1.19:7001;branch=z9hG4bK]]
 1(2262224) DEBUG: <core> [core/parser/parse_fline.c:249]: parse_first_line(): first line type 1 (request) flags 1
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:677]: parse_msg(): SIP Request:
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:678]: parse_msg():  method:  <INVITE>
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:680]: parse_msg():  uri:     <sip:100@172.16.1.19:7060;user=phone>
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:682]: parse_msg():  version: <SIP/2.0>
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:324]: parse_headers(): flags=2
 1(2262224) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Via] type 1
 1(2262224) DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKa1-1>; state=16
 1(2262224) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:555]: parse_headers(): Via found, flags=2
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:557]: parse_headers(): this is the first via
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_headers(): header field type 1, name=<Via>, body=<SIP/2.0/UDP 172.16.1.19:7001;branch=z9hG4bKa1-1>
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:709]: parse_msg(): first via: <SIP/2.0/UDP> <172.16.1.19:7001(7001)> 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:717]: parse_msg(): ;<> 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:720]: parse_msg(): 
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:740]: parse_msg(): exiting parse_msg
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:324]: parse_headers(): flags=78
 1(2262224) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [From] type 4
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_headers(): header field type 4, name=<From>, body=<"test" <sip:+12345678@172.16.1.19:7001>;tag=1>
 1(2262224) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [To] type 3
 1(2262224) DEBUG: <core> [core/parser/parse_addr_spec.c:884]: parse_addr_spec(): end of header reached, state=10
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [39]; uri=[sip:100@172.16.1.19:7060;user=phone]
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (39)[<sip:100@172.16.1.19:7060;user=phone>
], to tag (0)[]
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_headers(): header field type 3, name=<To>, body=<<sip:100@172.16.1.19:7060;user=phone>>
 1(2262224) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Call-ID] type 6
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_headers(): header field type 6, name=<Call-ID>, body=<1-2262234@172.16.1.19>
 1(2262224) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [CSeq] type 5
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE>
 1(2262224) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_headers(): header field type 5, name=<CSeq>, body=<1 INVITE>
 1(2262224) DEBUG: <core> [core/receive.c:389]: receive_msg(): --- received sip message - request - call-id: [1-2262234@172.16.1.19] - cseq: [1 INVITE]
 1(2262224) DEBUG: <core> [core/receive.c:261]: ksr_evrt_pre_routing(): event route core:pre-routing not defined
 1(2262224) DEBUG: <core> [core/receive.c:471]: receive_msg(): preparing to run routing scripts...
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1252]: parse_uri(): state=11
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1253]: parse_uri(): parsed uri:
 type=3 user=<100>(3)
 passwd=<>(0)
 host=<172.16.1.19>(11)
 port=<7060>(4): 7060
 params=<>(0)
 headers=<>(0)
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1264]: parse_uri():  uri flags :  1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1266]: parse_uri(): sip_user_phone  1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1267]: parse_uri():    value=2
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1268]: parse_uri():  uri params:
   transport=<>, val=<>, proto=0
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1271]: parse_uri():    user-param=<user=phone>, val=<phone>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1274]: parse_uri():    method=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1277]: parse_uri():    ttl=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1280]: parse_uri():    maddr=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1283]: parse_uri():    lr=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1284]: parse_uri():    r2=<>
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 23
 1(2262224) NOTICE: Incoming INVITE to 100
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 42
 1(2262224) INFO: $ru = sip:100@172.16.1.19:7060;user=phone
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 27
 1(2262224) NOTICE: Set $rU = "1234;first=yes"
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1252]: parse_uri(): state=11
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1253]: parse_uri(): parsed uri:
 type=3 user=<1234>(4)
 passwd=<>(0)
 host=<172.16.1.19>(11)
 port=<7060>(4): 7060
 params=<first=yes>(9)
 headers=<>(0)
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1264]: parse_uri():  uri flags :  1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1266]: parse_uri(): sip_user_phone  1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1267]: parse_uri():    value=2
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1268]: parse_uri():  uri params:
   transport=<>, val=<>, proto=0
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1271]: parse_uri():    user-param=<user=phone>, val=<phone>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1274]: parse_uri():    method=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1277]: parse_uri():    ttl=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1280]: parse_uri():    maddr=<>, val=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1283]: parse_uri():    lr=<>
 1(2262224) DEBUG: <core> [core/parser/parse_uri.c:1284]: parse_uri():    r2=<>
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 52
 1(2262224) INFO: $ru: sip:1234;first=yes@172.16.1.19:7060;user=phone
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 10
 1(2262224) INFO: $rU: 1234
 1(2262224) DEBUG: <core> [core/pvapi.c:1509]: pv_printf_mode(): final buffer length 6
 1(2262224) ERROR: Fail!