- [2020-01-14 15:45:52] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP request (834 bytes) from UDP:203.55.231.200:5060 --->
- INVITE sip:0731224895@192.168.100.100:5060 SIP/2.0
- Via: SIP/2.0/UDP 203.55.231.200:5060;branch=z9hG4bKe5nnmc1078npqre5b2e0.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known"<sip:0731224895@iinetphone.iinet.net.au>
- Call-ID: BW054552294140120672660613@10.11.1.1
- CSeq: 290135508 INVITE
- Contact: <sip:0412644815@203.55.231.200:5060;transport=udp>
- Supported: 100rel
- Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY
- Recv-Info: x-broadworks-client-session-info
- Accept: application/media_control+xml,application/sdp,multipart/mixed
- Max-Forwards: 69
- Content-Type: application/sdp
- Content-Length: 176
- v=0
- o=BroadWorks 112730972 1 IN IP4 203.55.231.203
- s=-
- c=IN IP4 203.55.231.203
- t=0 0
- m=audio 46426 RTP/AVP 8 0 18 101
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-15
- [2020-01-14 15:45:52] VERBOSE[2348] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '192.168.100.100'
- [2020-01-14 15:45:52] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP response (389 bytes) to UDP:203.55.231.200:5060 --->
- SIP/2.0 100 Trying
- Via: SIP/2.0/UDP 203.55.231.200:5060;rport=5060;received=203.55.231.200;branch=z9hG4bKe5nnmc1078npqre5b2e0.1
- Call-ID: BW054552294140120672660613@10.11.1.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>
- CSeq: 290135508 INVITE
- Server: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:1] Set("PJSIP/iinet-00000002", "__DIRECTION=INBOUND") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:2] Gosub("PJSIP/iinet-00000002", "sub-record-check,s,1(in,0731224895,dontcare)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/iinet-00000002", "0?initialized") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/iinet-00000002", "__REC_STATUS=INITIALIZED") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/iinet-00000002", "NOW=1578980752") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/iinet-00000002", "__DAY=14") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/iinet-00000002", "__MONTH=01") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/iinet-00000002", "__YEAR=2020") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/iinet-00000002", "__TIMESTR=20200114-154552") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/iinet-00000002", "__FROMEXTEN=unknown") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/iinet-00000002", "__MON_FMT=wav") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/iinet-00000002", "Recordings initialized") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/iinet-00000002", "0?Set(ARG3=dontcare)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/iinet-00000002", "REC_POLICY_MODE_SAVE=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/iinet-00000002", "0?Set(REC_STATUS=NO)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/iinet-00000002", "2?checkaction") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/iinet-00000002", "1?sub-record-check,in,1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,in,1)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/iinet-00000002", "Inbound Recording Check to 0731224895") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/iinet-00000002", "FROMEXTEN=unknown") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/iinet-00000002", "10?Set(FROMEXTEN=0412644815)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/iinet-00000002", "recordcheck,1(dontcare,in,0731224895)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/iinet-00000002", "Starting recording check against dontcare") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/iinet-00000002", "dontcare") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:3] Set("PJSIP/iinet-00000002", "__FROM_DID=0731224895") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:4] Set("PJSIP/iinet-00000002", "returnhere=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:5] Gosub("PJSIP/iinet-00000002", "app-blacklist-check,s,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/iinet-00000002", "0?blacklisted") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/iinet-00000002", "CALLED_BLACKLIST=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:6] Set("PJSIP/iinet-00000002", "CDR(did)=0731224895") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:7] GotoIf("PJSIP/iinet-00000002", "0?") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:8] ExecIf("PJSIP/iinet-00000002", "1 ?Set(CALLERID(name)=0412644815)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:9] Set("PJSIP/iinet-00000002", "__MOHCLASS=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:10] Set("PJSIP/iinet-00000002", "__REVERSAL_REJECT=FALSE") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:11] GotoIf("PJSIP/iinet-00000002", "1?post-reverse-charge") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (from-pstn,0731224895,13)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:13] NoOp("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:14] Set("PJSIP/iinet-00000002", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:15] Set("PJSIP/iinet-00000002", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:16] Set("PJSIP/iinet-00000002", "CALLERID(name-pres)=allowed_not_screened") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:17] Set("PJSIP/iinet-00000002", "CALLERID(num-pres)=allowed_not_screened") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:18] NoOp("PJSIP/iinet-00000002", "CallerID Entry Point") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [0731224895@from-pstn:19] Goto("PJSIP/iinet-00000002", "from-did-direct,101,1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (from-did-direct,101,1)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [101@from-did-direct:1] GotoIf("PJSIP/iinet-00000002", "1?ext-local,101,1:followme-check,101,1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (ext-local,101,1)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [101@ext-local:1] Set("PJSIP/iinet-00000002", "__RINGTIMER=15") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [101@ext-local:2] Macro("PJSIP/iinet-00000002", "exten-vm,novm,101,0,0,0") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/iinet-00000002", "user-callerid,") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/iinet-00000002", "TOUCH_MONITOR=1578980752.2") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/iinet-00000002", "AMPUSER=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/iinet-00000002", "0?report") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/iinet-00000002", "1?Set(REALCALLERIDNUM=0412644815)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/iinet-00000002", "AMPUSER=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/iinet-00000002", "0?limit") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/iinet-00000002", "AMPUSERCIDNAME=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/iinet-00000002", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/iinet-00000002", "1?report") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,16)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:16] NoOp("PJSIP/iinet-00000002", "Macro Depth is 2") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/iinet-00000002", "1?report2:macroerror") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,18)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("PJSIP/iinet-00000002", "0?continue") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("PJSIP/iinet-00000002", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/iinet-00000002", "__TTL=64") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("PJSIP/iinet-00000002", "1?continue") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,37)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:37] Set("PJSIP/iinet-00000002", "CALLERID(number)=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/iinet-00000002", "CALLERID(name)=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("PJSIP/iinet-00000002", "0?cnum") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:40] Set("PJSIP/iinet-00000002", "CDR(cnam)=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:41] Set("PJSIP/iinet-00000002", "CDR(cnum)=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/iinet-00000002", "CHANNEL(language)=en_AU") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/iinet-00000002", "RingGroupMethod=none") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/iinet-00000002", "__EXTTOCALL=101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/iinet-00000002", "__PICKUPMARK=101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/iinet-00000002", "RT=") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("PJSIP/iinet-00000002", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("PJSIP/iinet-00000002", "0?Gosub(ext-intercom,*80101,1())") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("PJSIP/iinet-00000002", "0?ChanSpy(PJSIP/101,q)") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] WARNING[4118][C-00000001] chan_sip.c: This function can only be used on SIP channels.
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("PJSIP/iinet-00000002", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("PJSIP/iinet-00000002", "0?Gosub(ext-intercom,*80101,1())") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("PJSIP/iinet-00000002", "0?ChanSpy(PJSIP/101,q)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("PJSIP/iinet-00000002", "0?MacroExit()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:18] Gosub("PJSIP/iinet-00000002", "sub-record-check,s,1(exten,101,dontcare)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/iinet-00000002", "10?initialized") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,10)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/iinet-00000002", "Recordings initialized") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/iinet-00000002", "0?Set(ARG3=dontcare)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/iinet-00000002", "REC_POLICY_MODE_SAVE=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/iinet-00000002", "0?Set(REC_STATUS=NO)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/iinet-00000002", "5?checkaction") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/iinet-00000002", "1?sub-record-check,exten,1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,1)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/iinet-00000002", "Exten Recording Check between 0412644815 and 101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/iinet-00000002", "CALLTYPE=external") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/iinet-00000002", "0?Set(CALLTYPE=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/iinet-00000002", "CALLEE=dontcare") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/iinet-00000002", "0?Set(CALLEE=dontcare)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/iinet-00000002", "1?callee") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,11)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:11] Gosub("PJSIP/iinet-00000002", "recordcheck,1(dontcare,external,101)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/iinet-00000002", "Starting recording check against dontcare") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/iinet-00000002", "dontcare") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [exten@sub-record-check:12] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("PJSIP/iinet-00000002", "1?macrodial") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-exten-vm,s,25)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("PJSIP/iinet-00000002", "0?clrheader,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-exten-vm:26] Macro("PJSIP/iinet-00000002", "dial-one,,Ttr,101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/iinet-00000002", "DEXTEN=101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:2] ExecIf("PJSIP/iinet-00000002", "0?Set(__EXTTOCALL=101)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:3] Set("PJSIP/iinet-00000002", "DIALSTATUS_CW=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:4] GosubIf("PJSIP/iinet-00000002", "0?screen,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:5] GosubIf("PJSIP/iinet-00000002", "0?cf,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:6] GotoIf("PJSIP/iinet-00000002", "1?skip1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,9)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:9] GotoIf("PJSIP/iinet-00000002", "0?nodial") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:10] GotoIf("PJSIP/iinet-00000002", "0?continue") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:11] Set("PJSIP/iinet-00000002", "EXTHASCW=ENABLED") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:12] GotoIf("PJSIP/iinet-00000002", "0?next1:cwinusebusy") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,24)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:24] GotoIf("PJSIP/iinet-00000002", "0?next3:continue") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,26)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:26] GotoIf("PJSIP/iinet-00000002", "0?nodial") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:27] GosubIf("PJSIP/iinet-00000002", "1?dstring,1():dlocal,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/iinet-00000002", "DSTRING=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/iinet-00000002", "DEVICES=101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/iinet-00000002", "0?Return()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/iinet-00000002", "0?Set(DEVICES=01)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/iinet-00000002", "LOOPCNT=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/iinet-00000002", "ITER=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/iinet-00000002", "THISDIAL=PJSIP/101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/iinet-00000002", "1?zap2dahdi,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/iinet-00000002", "0?Return()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/iinet-00000002", "NEWDIAL=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/iinet-00000002", "LOOPCNT2=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/iinet-00000002", "ITER2=1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/iinet-00000002", "THISPART2=PJSIP/101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/iinet-00000002", "0?Set(THISPART2=DAHDIIP/101)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/iinet-00000002", "NEWDIAL=PJSIP/101&") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/iinet-00000002", "ITER2=2") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/iinet-00000002", "0?begin2") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/iinet-00000002", "THISDIAL=PJSIP/101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/iinet-00000002", "0?docheck") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/iinet-00000002", "Debug: Found PJSIP Destination PJSIP/101") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("PJSIP/iinet-00000002", "0?doset") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("PJSIP/iinet-00000002", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/iinet-00000002", "THISDIAL=PJSIP/101/sip:101@192.168.100.101:5060;transport=udp") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/iinet-00000002", "0?skipset") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:15] Set("PJSIP/iinet-00000002", "DSTRING=PJSIP/101/sip:101@192.168.100.101:5060;transport=udp&") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:16] Set("PJSIP/iinet-00000002", "ITER=2") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("PJSIP/iinet-00000002", "0?begin") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("PJSIP/iinet-00000002", "0?Return()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:19] Set("PJSIP/iinet-00000002", "DSTRING=PJSIP/101/sip:101@192.168.100.101:5060;transport=udp") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [dstring@macro-dial-one:20] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:28] GotoIf("PJSIP/iinet-00000002", "0?nodial") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/iinet-00000002", "0?skiptrace") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:30] GosubIf("PJSIP/iinet-00000002", "1?ctset,1():ctclear,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/iinet-00000002", "DB(CALLTRACE/101)=0412644815") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:31] Set("PJSIP/iinet-00000002", "D_OPTIONS=Ttr") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:32] GosubIf("PJSIP/iinet-00000002", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:33] NoOp("PJSIP/iinet-00000002", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:34] ExecIf("PJSIP/iinet-00000002", "0?Set(ALERT_INFO=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:35] ExecIf("PJSIP/iinet-00000002", "0?Set(ALERT_INFO=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:36] ExecIf("PJSIP/iinet-00000002", "0?Set(ALERT_INFO=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:37] ExecIf("PJSIP/iinet-00000002", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:38] ExecIf("PJSIP/iinet-00000002", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:39] GosubIf("PJSIP/iinet-00000002", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:40] ExecIf("PJSIP/iinet-00000002", "0?Set(CHANNEL(musicclass)=)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:41] GosubIf("PJSIP/iinet-00000002", "0?qwait,1()") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:42] Set("PJSIP/iinet-00000002", "__CWIGNORE=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:43] Set("PJSIP/iinet-00000002", "__KEEPCID=TRUE") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:44] GotoIf("PJSIP/iinet-00000002", "0?usegoto,1") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:45] GotoIf("PJSIP/iinet-00000002", "1?godial") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,50)
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:50] Macro("PJSIP/iinet-00000002", "dialout-one-predial-hook,") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:51] ExecIf("PJSIP/iinet-00000002", "1?Set(D_OPTIONS=trI)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:52] NoOp("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:53] ExecIf("PJSIP/iinet-00000002", "0?Set(D_OPTIONS=trIg)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-dial-one:54] Dial("PJSIP/iinet-00000002", "PJSIP/101/sip:101@192.168.100.101:5060;transport=udp,,trIb(func-apply-sipheaders^s^1)") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_stack.c: PJSIP/101-00000003 Internal Gosub(func-apply-sipheaders,s,1) start
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/101-00000003", "Applying SIP Headers to channel PJSIP/101-00000003") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/101-00000003", "TECH=PJSIP") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/101-00000003", "SIPHEADERKEYS=") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:4] While("PJSIP/101-00000003", "0") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_while.c: Jumping to priority 12
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:13] Return("PJSIP/101-00000003", "") in new stack
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'PJSIP/101-00000003'
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_stack.c: PJSIP/101-00000003 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_dial.c: Called PJSIP/101/sip:101@192.168.100.101:5060;transport=udp
- [2020-01-14 15:45:52] VERBOSE[4119] res_pjsip_logger.c: <--- Transmitting SIP response (579 bytes) to UDP:203.55.231.200:5060 --->
- SIP/2.0 180 Ringing
- Via: SIP/2.0/UDP 203.55.231.200:5060;rport=5060;received=203.55.231.200;branch=z9hG4bKe5nnmc1078npqre5b2e0.1
- Call-ID: BW054552294140120672660613@10.11.1.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- CSeq: 290135508 INVITE
- Server: FPBX-13.0.197.21(13.29.2)
- Contact: <sip:192.168.100.100:5060>
- Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE
- Content-Length: 0
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_dial.c: Connected line update to PJSIP/iinet-00000002 prevented.
- [2020-01-14 15:45:52] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP request (963 bytes) to UDP:192.168.100.101:5060 --->
- INVITE sip:101@192.168.100.101:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj2cc76a5a-6bd4-4413-93a5-d79ef989afc6
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>
- Contact: <sip:asterisk@192.168.100.100:5060>
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- CSeq: 14872 INVITE
- Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE
- Supported: 100rel, timer, replaces, norefersub
- Session-Expires: 1800
- Min-SE: 90
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Type: application/sdp
- Content-Length: 261
- v=0
- o=- 4411463 4411463 IN IP4 192.168.100.100
- s=Asterisk
- c=IN IP4 192.168.100.100
- t=0 0
- m=audio 16344 RTP/AVP 8 0 101
- a=rtpmap:8 PCMA/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- a=maxptime:150
- a=sendrecv
- [2020-01-14 15:45:52] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (556 bytes) from UDP:192.168.100.101:49156 --->
- SIP/2.0 100 Trying
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj2cc76a5a-6bd4-4413-93a5-d79ef989afc6
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- Date: Tue, 14 Jan 2020 05:45:52 GMT
- CSeq: 14872 INVITE
- Server: Cisco-CP7961G/8.5.2
- Contact: <sip:101@192.168.100.101:5060;transport=udp>
- Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
- Allow-Events: kpml,dialog
- Content-Length: 0
- [2020-01-14 15:45:52] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (595 bytes) from UDP:192.168.100.101:49156 --->
- SIP/2.0 180 Ringing
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj2cc76a5a-6bd4-4413-93a5-d79ef989afc6
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>;tag=001bd4607964087969dd76f6-4ac27638
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- Date: Tue, 14 Jan 2020 05:45:52 GMT
- CSeq: 14872 INVITE
- Server: Cisco-CP7961G/8.5.2
- Contact: <sip:101@192.168.100.101:5060;transport=udp>
- Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
- Allow-Events: kpml,dialog
- Content-Length: 0
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_dial.c: PJSIP/101-00000003 is ringing
- [2020-01-14 15:45:52] VERBOSE[4118][C-00000001] app_dial.c: PJSIP/101-00000003 is ringing
- [2020-01-14 15:45:52] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP response (579 bytes) to UDP:203.55.231.200:5060 --->
- SIP/2.0 180 Ringing
- Via: SIP/2.0/UDP 203.55.231.200:5060;rport=5060;received=203.55.231.200;branch=z9hG4bKe5nnmc1078npqre5b2e0.1
- Call-ID: BW054552294140120672660613@10.11.1.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- CSeq: 290135508 INVITE
- Server: FPBX-13.0.197.21(13.29.2)
- Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE
- Contact: <sip:192.168.100.100:5060>
- Content-Length: 0
- [2020-01-14 15:45:55] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP request (451 bytes) to UDP:192.168.100.101:5060 --->
- OPTIONS sip:201@192.168.100.101:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj82f51be6-dde3-4a83-998d-da0c7ef093a2
- From: <sip:201@192.168.100.100>;tag=0ea8e01e-c67b-4ea3-9c8c-70fb63fa61d9
- To: <sip:201@192.168.100.101>
- Contact: <sip:201@192.168.100.100:5060>
- Call-ID: 44fe342d-3c5d-4275-b279-24eafd2e9436
- CSeq: 22124 OPTIONS
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:45:55] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (980 bytes) from UDP:192.168.100.101:51321 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj82f51be6-dde3-4a83-998d-da0c7ef093a2
- From: <sip:201@192.168.100.100>;tag=0ea8e01e-c67b-4ea3-9c8c-70fb63fa61d9
- To: <sip:201@192.168.100.101>;tag=001bd4607964087a88927366-43a77868
- Call-ID: 44fe342d-3c5d-4275-b279-24eafd2e9436
- Date: Tue, 14 Jan 2020 05:45:55 GMT
- CSeq: 22124 OPTIONS
- Server: Cisco-CP7961G/8.5.2
- Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
- Allow-Events: kpml,dialog,refer
- Accept: application/sdp,multipart/mixed,multipart/alternative
- Accept-Encoding: identity
- Accept-Language: en
- Supported: replaces,join,norefersub
- Content-Length: 239
- Content-Type: application/sdp
- Content-Disposition: session;handling=optional
- v=0
- o=Cisco-SIPUA 8541 0 IN IP4 192.168.100.101
- s=SIP Call
- t=0 0
- m=audio 0 RTP/AVP 0 8 18 101
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:18 G729/8000
- a=fmtp:18 annexb=no
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-15
- [2020-01-14 15:45:56] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (919 bytes) from UDP:192.168.100.101:49156 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj2cc76a5a-6bd4-4413-93a5-d79ef989afc6
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>;tag=001bd4607964087969dd76f6-4ac27638
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- Date: Tue, 14 Jan 2020 05:45:56 GMT
- CSeq: 14872 INVITE
- Server: Cisco-CP7961G/8.5.2
- Contact: <sip:101@192.168.100.101:5060;transport=udp>
- Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
- Supported: replaces,join,norefersub
- Allow-Events: kpml,dialog
- Content-Length: 211
- Content-Type: application/sdp
- Content-Disposition: session;handling=optional
- v=0
- o=Cisco-SIPUA 15169 0 IN IP4 192.168.100.101
- s=SIP Call
- t=0 0
- m=audio 24062 RTP/AVP 8 101
- c=IN IP4 192.168.100.101
- a=rtpmap:8 PCMA/8000
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-15
- a=sendrecv
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] app_dial.c: PJSIP/101-00000003 answered PJSIP/iinet-00000002
- [2020-01-14 15:45:56] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP request (460 bytes) to UDP:192.168.100.101:5060 --->
- ACK sip:101@192.168.100.101:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj24dea3a9-3870-4d69-82c8-8907601cd6a9
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>;tag=001bd4607964087969dd76f6-4ac27638
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- CSeq: 14872 ACK
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:45:56] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP response (913 bytes) to UDP:203.55.231.200:5060 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 203.55.231.200:5060;rport=5060;received=203.55.231.200;branch=z9hG4bKe5nnmc1078npqre5b2e0.1
- Call-ID: BW054552294140120672660613@10.11.1.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- CSeq: 290135508 INVITE
- Server: FPBX-13.0.197.21(13.29.2)
- Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE
- Contact: <sip:192.168.100.100:5060>
- Supported: 100rel, timer, replaces, norefersub
- Content-Type: application/sdp
- Content-Length: 257
- v=0
- o=- 112730972 3 IN IP4 192.168.100.100
- s=Asterisk
- c=IN IP4 192.168.100.100
- t=0 0
- m=audio 19474 RTP/AVP 8 0 101
- a=rtpmap:8 PCMA/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- a=maxptime:150
- a=sendrecv
- [2020-01-14 15:45:56] VERBOSE[4124][C-00000001] bridge_channel.c: Channel PJSIP/101-00000003 joined 'simple_bridge' basic-bridge <25e4e758-55f7-4f88-9cd8-d2fd9ba12ef0>
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] bridge_channel.c: Channel PJSIP/iinet-00000002 joined 'simple_bridge' basic-bridge <25e4e758-55f7-4f88-9cd8-d2fd9ba12ef0>
- [2020-01-14 15:45:56] WARNING[4118][C-00000001] translate.c: No translator path: (ending codec is not valid)
- [2020-01-14 15:45:56] WARNING[4118][C-00000001] translate.c: No translator path: (ending codec is not valid)
- [2020-01-14 15:45:56] WARNING[4124][C-00000001] channel.c: Unable to find a codec translation path: (g729) -> (alaw)
- [2020-01-14 15:45:56] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP request (453 bytes) from UDP:203.55.231.200:5060 --->
- ACK sip:192.168.100.100:5060 SIP/2.0
- Via: SIP/2.0/UDP 203.55.231.200:5060;branch=z9hG4bKae4gvg008ggmmuist3s0.1
- From: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- To: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- Call-ID: BW054552294140120672660613@10.11.1.1
- CSeq: 290135508 ACK
- Contact: <sip:0412644815@203.55.231.200:5060;transport=udp>
- Max-Forwards: 69
- Content-Length: 0
- [2020-01-14 15:45:56] VERBOSE[4124][C-00000001] bridge_channel.c: Channel PJSIP/101-00000003 left 'simple_bridge' basic-bridge <25e4e758-55f7-4f88-9cd8-d2fd9ba12ef0>
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] bridge_channel.c: Channel PJSIP/iinet-00000002 left 'simple_bridge' basic-bridge <25e4e758-55f7-4f88-9cd8-d2fd9ba12ef0>
- [2020-01-14 15:45:56] WARNING[4118][C-00000001] channel.c: Unable to find a codec translation path: (g729) -> (alaw)
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] app_macro.c: Spawn extension (macro-dial-one, s, 54) exited non-zero on 'PJSIP/iinet-00000002' in macro 'dial-one'
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on 'PJSIP/iinet-00000002' in macro 'exten-vm'
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Spawn extension (ext-local, 101, 2) exited non-zero on 'PJSIP/iinet-00000002'
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/iinet-00000002", "hangupcall,") in new stack
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/iinet-00000002", "1?theend") in new stack
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,3)
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/iinet-00000002", "0?Set(CDR(recordingfile)=)") in new stack
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/iinet-00000002", "") in new stack
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/iinet-00000002' in macro 'hangupcall'
- [2020-01-14 15:45:56] VERBOSE[4118][C-00000001] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/iinet-00000002'
- [2020-01-14 15:45:56] VERBOSE[4119] res_pjsip_logger.c: <--- Transmitting SIP request (484 bytes) to UDP:192.168.100.101:5060 --->
- BYE sip:101@192.168.100.101:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj92869b8c-e1a6-43b1-9a9e-1268d8a8702a
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>;tag=001bd4607964087969dd76f6-4ac27638
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- CSeq: 14873 BYE
- Reason: Q.850;cause=16
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:45:56] VERBOSE[4119] res_pjsip_logger.c: <--- Transmitting SIP request (499 bytes) to UDP:203.55.231.200:5060 --->
- BYE sip:0412644815@203.55.231.200:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj6682aab5-0033-4c25-9d6f-c06c665609cc
- From: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- To: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- Call-ID: BW054552294140120672660613@10.11.1.1
- CSeq: 28207 BYE
- Reason: Q.850;cause=16
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:45:56] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (401 bytes) from UDP:203.55.231.200:5060 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 192.168.100.100:5060;received=192.168.100.100;branch=z9hG4bKPj6682aab5-0033-4c25-9d6f-c06c665609cc;rport=1024
- From: "Not Known" <sip:0731224895@iinetphone.iinet.net.au>;tag=05ce0f28-2f5d-4310-a33c-35da683dfae6
- To: <sip:0412644815@10.11.1.1;user=phone>;tag=38273994-1578980752294-
- Call-ID: BW054552294140120672660613@10.11.1.1
- CSeq: 28207 BYE
- Content-Length: 0
- [2020-01-14 15:45:56] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (428 bytes) from UDP:192.168.100.101:49156 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj92869b8c-e1a6-43b1-9a9e-1268d8a8702a
- From: "0412644815" <sip:0412644815@192.168.100.100>;tag=6600265c-6ad2-485a-b1d3-847aa1a17979
- To: <sip:101@192.168.100.101>;tag=001bd4607964087969dd76f6-4ac27638
- Call-ID: 3bf4c47f-a818-46bc-9941-08c4dd3d8e87
- Date: Tue, 14 Jan 2020 05:45:56 GMT
- CSeq: 14873 BYE
- Server: Cisco-CP7961G/8.5.2
- Content-Length: 0
- [2020-01-14 15:46:01] SECURITY[2387] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2020-01-14T15:46:01.700+1000",Severity="Informational",Service="AMI",EventVersion="1",AccountID="admin",SessionID="0x7f9cb8016708",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/127.0.0.1/49330",UsingPassword="0",SessionTV="2020-01-14T15:46:01.700+1000"
- [2020-01-14 15:46:01] SECURITY[2387] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2020-01-14T15:46:01.700+1000",Severity="Informational",Service="AMI",EventVersion="1",AccountID="admin",SessionID="0x7f9cb800aab8",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/127.0.0.1/49332",UsingPassword="0",SessionTV="2020-01-14T15:46:01.700+1000"
- [2020-01-14 15:46:01] SECURITY[2387] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2020-01-14T15:46:01.703+1000",Severity="Informational",Service="AMI",EventVersion="1",AccountID="admin",SessionID="0x7f9cb8015d78",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/127.0.0.1/49338",UsingPassword="0",SessionTV="2020-01-14T15:46:01.703+1000"
- [2020-01-14 15:46:01] SECURITY[2387] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2020-01-14T15:46:01.704+1000",Severity="Informational",Service="AMI",EventVersion="1",AccountID="admin",SessionID="0x7f9cb800b438",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/127.0.0.1/49339",UsingPassword="0",SessionTV="2020-01-14T15:46:01.704+1000"
- [2020-01-14 15:46:02] SECURITY[2387] res_security_log.c: SecurityEvent="SuccessfulAuth",EventTV="2020-01-14T15:46:02.434+1000",Severity="Informational",Service="AMI",EventVersion="1",AccountID="admin",SessionID="0x7f9cb8016708",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/127.0.0.1/49344",UsingPassword="0",SessionTV="2020-01-14T15:46:02.434+1000"
- [2020-01-14 15:46:09] VERBOSE[2348] res_pjsip_logger.c: <--- Transmitting SIP request (450 bytes) to UDP:192.168.100.101:5060 --->
- OPTIONS sip:101@192.168.100.101:5060;transport=udp SIP/2.0
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj17e83f11-e423-479a-8a81-5f690c1ed666
- From: <sip:101@192.168.100.100>;tag=ed75acf7-2735-41dc-9797-663d3d9bb6df
- To: <sip:101@192.168.100.101>
- Contact: <sip:101@192.168.100.100:5060>
- Call-ID: c27afe5b-c568-4b0a-97ee-f4b845b4f1c2
- CSeq: 7093 OPTIONS
- Max-Forwards: 70
- User-Agent: FPBX-13.0.197.21(13.29.2)
- Content-Length: 0
- [2020-01-14 15:46:09] VERBOSE[2347] res_pjsip_logger.c: <--- Received SIP response (980 bytes) from UDP:192.168.100.101:51322 --->
- SIP/2.0 200 OK
- Via: SIP/2.0/UDP 192.168.100.100:5060;rport;branch=z9hG4bKPj17e83f11-e423-479a-8a81-5f690c1ed666
- From: <sip:101@192.168.100.100>;tag=ed75acf7-2735-41dc-9797-663d3d9bb6df
- To: <sip:101@192.168.100.101>;tag=001bd4607964087b194e53b2-d30e8836
- Call-ID: c27afe5b-c568-4b0a-97ee-f4b845b4f1c2
- Date: Tue, 14 Jan 2020 05:46:09 GMT
- CSeq: 7093 OPTIONS
- Server: Cisco-CP7961G/8.5.2
- Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
- Allow-Events: kpml,dialog,refer
- Accept: application/sdp,multipart/mixed,multipart/alternative
- Accept-Encoding: identity
- Accept-Language: en
- Supported: replaces,join,norefersub
- Content-Length: 240
- Content-Type: application/sdp
- Content-Disposition: session;handling=optional
- v=0
- o=Cisco-SIPUA 13362 0 IN IP4 192.168.100.101
- s=SIP Call
- t=0 0
- m=audio 0 RTP/AVP 0 8 18 101
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:18 G729/8000
- a=fmtp:18 annexb=no
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-15