Debug log with EXTRA_DEBUG enabled for call scenario 100: ``` 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! ```