[2018-09-29 13:49:45] VERBOSE[26421] res_pjsip_logger.c: <--- Received SIP request (1233 bytes) from UDP:162.213.111.22:5060 ---> INVITE sip:16475558888@162.213.111.22:5060;transport=UDP SIP/2.0 Record-Route: Via: SIP/2.0/UDP 162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---40bce249ebb11176;rport=5060 Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-r3yhm5gzaorqw6mn;rport=5061 Max-Forwards: 69 Record-Route: Contact: "Anonymous" To: From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o CSeq: 723 INVITE Expires: 300 Content-Disposition: session Content-Type: application/sdp User-Agent: Sippy h323-conf-id: 1700547733-3614901725-2035232854-1600231106 Portasip-3264-action: offer 1 cisco-GUID: 1700547733-3614901725-2035232854-1600231106 Content-Length: 292 v=0 o=Sippy 1870641837579328380 0 IN IP4 208.65.240.165 s=Asterisk PBX 13.23.1 t=0 0 m=audio 39316 RTP/AVP 0 18 101 c=IN IP4 208.65.240.142 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [2018-09-29 13:49:45] VERBOSE[26422] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '162.213.111.22' [2018-09-29 13:49:45] VERBOSE[26422] res_pjsip_logger.c: <--- Transmitting SIP response (658 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Content-Length: 0 [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:1] Set("PJSIP/6475558888-pjsip-00000000", "__FROM_DID=16475558888") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:2] Set("PJSIP/6475558888-pjsip-00000000", "__CATCHALL_DID=16475558888") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:3] NoOp("PJSIP/6475558888-pjsip-00000000", "Catch-All DID Match - Found 16475558888 - You probably want a DID for this.") in new stack [2018-09-29 13:49:45] WARNING[26687][C-00000000] chan_sip.c: This function can only be used on SIP channels. [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:4] Log("PJSIP/6475558888-pjsip-00000000", "WARNING,Friendly Scanner from ") in new stack [2018-09-29 13:49:45] WARNING[26687][C-00000000] Ext. 16475558888: Friendly Scanner from [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:5] Set("PJSIP/6475558888-pjsip-00000000", "__FROM_DID=16475558888") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [16475558888@from-pstn:6] Goto("PJSIP/6475558888-pjsip-00000000", "ext-did,s,1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (ext-did,s,1) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:1] Set("PJSIP/6475558888-pjsip-00000000", "__DIRECTION=INBOUND") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:2] Gosub("PJSIP/6475558888-pjsip-00000000", "sub-record-check,s,1(in,s,dontcare)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?initialized") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/6475558888-pjsip-00000000", "__REC_STATUS=INITIALIZED") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/6475558888-pjsip-00000000", "NOW=1538279385") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/6475558888-pjsip-00000000", "__DAY=29") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/6475558888-pjsip-00000000", "__MONTH=09") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/6475558888-pjsip-00000000", "__YEAR=2018") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/6475558888-pjsip-00000000", "__TIMESTR=20180929-234945") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/6475558888-pjsip-00000000", "__FROMEXTEN=unknown") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/6475558888-pjsip-00000000", "__MON_FMT=wav") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/6475558888-pjsip-00000000", "Recordings initialized") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/6475558888-pjsip-00000000", "0?Set(ARG3=dontcare)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/6475558888-pjsip-00000000", "REC_POLICY_MODE_SAVE=") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/6475558888-pjsip-00000000", "0?Set(REC_STATUS=NO)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/6475558888-pjsip-00000000", "2?checkaction") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (sub-record-check,s,17) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/6475558888-pjsip-00000000", "1?sub-record-check,in,1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (sub-record-check,in,1) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/6475558888-pjsip-00000000", "Inbound Recording Check to s") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/6475558888-pjsip-00000000", "FROMEXTEN=unknown") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/6475558888-pjsip-00000000", "11?Set(FROMEXTEN=14165559999)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/6475558888-pjsip-00000000", "recordcheck,1(dontcare,in,s)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/6475558888-pjsip-00000000", "Starting recording check against dontcare") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/6475558888-pjsip-00000000", "dontcare") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:3] Set("PJSIP/6475558888-pjsip-00000000", "CHANNEL(tonezone)=us") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:4] ExecIf("PJSIP/6475558888-pjsip-00000000", "0?Set(__FROM_DID=s)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:5] Set("PJSIP/6475558888-pjsip-00000000", "returnhere=1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:6] Gosub("PJSIP/6475558888-pjsip-00000000", "app-blacklist-check,s,1()") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?blacklisted") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/6475558888-pjsip-00000000", "CALLED_BLACKLIST=1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:7] Set("PJSIP/6475558888-pjsip-00000000", "CDR(did)=16475558888") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:8] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:9] ExecIf("PJSIP/6475558888-pjsip-00000000", "0 ?Set(CALLERID(name)=14165559999)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:10] Set("PJSIP/6475558888-pjsip-00000000", "__MOHCLASS=") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:11] Set("PJSIP/6475558888-pjsip-00000000", "__REVERSAL_REJECT=FALSE") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:12] GotoIf("PJSIP/6475558888-pjsip-00000000", "1?post-reverse-charge") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (ext-did,s,14) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:14] NoOp("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:15] Macro("PJSIP/6475558888-pjsip-00000000", "privacy-mgr,3,10") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:1] Set("PJSIP/6475558888-pjsip-00000000", "KEEPCID=14165559999") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:2] Set("PJSIP/6475558888-pjsip-00000000", "TESTCID=14164958883.000000") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:3] ExecIf("PJSIP/6475558888-pjsip-00000000", "0?Set(CALLERID(num)=)") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:4] PrivacyManager("PJSIP/6475558888-pjsip-00000000", "3,10") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] app_privacy.c: CallerID number present: Skipping [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:5] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?fail") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:6] GosubIf("PJSIP/6475558888-pjsip-00000000", "1?app-blacklist-check,s,1()") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?blacklisted") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/6475558888-pjsip-00000000", "CALLED_BLACKLIST=1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:7] Set("PJSIP/6475558888-pjsip-00000000", "CALLERID(num-pres)=allowed_passed_screen") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@macro-privacy-mgr:8] MacroExit("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:16] NoOp("PJSIP/6475558888-pjsip-00000000", "CallerID Entry Point") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ext-did:17] Goto("PJSIP/6475558888-pjsip-00000000", "ivr-11,s,1") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (ivr-11,s,1) [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:1] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT_LOOPCOUNT=0") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:2] Set("PJSIP/6475558888-pjsip-00000000", "INVALID_LOOPCOUNT=0") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:3] Set("PJSIP/6475558888-pjsip-00000000", "_IVR_CONTEXT_ivr-11=") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:4] Set("PJSIP/6475558888-pjsip-00000000", "_IVR_CONTEXT=ivr-11") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:5] Set("PJSIP/6475558888-pjsip-00000000", "__IVR_RETVM=") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:6] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?skip") in new stack [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:7] Answer("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:49:45] VERBOSE[26422] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:45] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:8] Wait("PJSIP/6475558888-pjsip-00000000", "1") in new stack [2018-09-29 13:49:45] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:46] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:9] Set("PJSIP/6475558888-pjsip-00000000", "IVR_MSG=custom/Main") in new stack [2018-09-29 13:49:46] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:10] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT(digit)=3") in new stack [2018-09-29 13:49:46] VERBOSE[26687][C-00000000] func_timeout.c: Digit timeout set to 3.000 [2018-09-29 13:49:46] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:11] ExecIf("PJSIP/6475558888-pjsip-00000000", "1?Background(custom/Main)") in new stack [2018-09-29 13:49:46] VERBOSE[26687][C-00000000] file.c: Playing 'custom/Main.slin' (language 'en') [2018-09-29 13:49:46] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:48] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:52] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:56] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:49:57] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:12] WaitExten("PJSIP/6475558888-pjsip-00000000", "3,") in new stack [2018-09-29 13:50:00] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx_builtins.c: Timeout on PJSIP/6475558888-pjsip-00000000, going to 't' [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:1] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT_LOOPCOUNT=1") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:2] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?final") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:3] Set("PJSIP/6475558888-pjsip-00000000", "IVR_MSG=custom/Main") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:4] Goto("PJSIP/6475558888-pjsip-00000000", "s,start") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (ivr-11,s,10) [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:10] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT(digit)=3") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] func_timeout.c: Digit timeout set to 3.000 [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:11] ExecIf("PJSIP/6475558888-pjsip-00000000", "1?Background(custom/Main)") in new stack [2018-09-29 13:50:00] VERBOSE[26687][C-00000000] file.c: Playing 'custom/Main.slin' (language 'en') [2018-09-29 13:50:04] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:50:08] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:50:12] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:12] WaitExten("PJSIP/6475558888-pjsip-00000000", "3,") in new stack [2018-09-29 13:50:12] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx_builtins.c: Timeout on PJSIP/6475558888-pjsip-00000000, going to 't' [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:1] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT_LOOPCOUNT=2") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:2] GotoIf("PJSIP/6475558888-pjsip-00000000", "0?final") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:3] Set("PJSIP/6475558888-pjsip-00000000", "IVR_MSG=custom/Main") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [t@ivr-11:4] Goto("PJSIP/6475558888-pjsip-00000000", "s,start") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx_builtins.c: Goto (ivr-11,s,10) [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:10] Set("PJSIP/6475558888-pjsip-00000000", "TIMEOUT(digit)=3") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] func_timeout.c: Digit timeout set to 3.000 [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] pbx.c: Executing [s@ivr-11:11] ExecIf("PJSIP/6475558888-pjsip-00000000", "1?Background(custom/Main)") in new stack [2018-09-29 13:50:15] VERBOSE[26687][C-00000000] file.c: Playing 'custom/Main.slin' (language 'en') [2018-09-29 13:50:16] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP response (1196 bytes) to UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 162.213.111.22;rport=5060;received=162.213.111.22;branch=z9hG4bK96bd.c8e53595.0 Via: SIP/2.0/UDP 208.65.240.165:5060;rport=5060;branch=z9hG4bK-524287-1---40bce249ebb11176 Via: SIP/2.0/UDP 208.65.240.165:5061;rport=5061;branch=z9hG4bK-r3yhm5gzaorqw6mn Record-Route: Record-Route: Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o From: "JOHN DOE" ;tag=uokmrxukcerwszzc.o To: ;tag=63812e13-00c1-4115-861d-2b897866ab02 CSeq: 723 INVITE Server: FPBX-14.0.3.19(13.23.1) Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 274 v=0 o=- 384195452 2 IN IP4 44.55.66.77 s=Asterisk c=IN IP4 44.55.66.77 t=0 0 m=audio 11386 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [2018-09-29 13:50:17] VERBOSE[26421] res_pjsip_logger.c: <--- Transmitting SIP request (547 bytes) to UDP:162.213.111.22:5060 ---> BYE sip:208.65.240.165:5061 SIP/2.0 Via: SIP/2.0/UDP 44.55.66.77:6667;rport;branch=z9hG4bKPjd37c1dc3-7db7-416b-9178-c978d06b974a From: ;tag=63812e13-00c1-4115-861d-2b897866ab02 To: "JOHN DOE" ;tag=uokmrxukcerwszzc.o Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o CSeq: 30656 BYE Route: Route: Max-Forwards: 70 User-Agent: FPBX-14.0.3.19(13.23.1) Content-Length: 0 [2018-09-29 13:50:17] VERBOSE[26421] res_pjsip_logger.c: <--- Received SIP response (429 bytes) from UDP:162.213.111.22:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 44.55.66.77:6667;rport=6667;branch=z9hG4bKPjd37c1dc3-7db7-416b-9178-c978d06b974a Record-Route: To: "JOHN DOE" ;tag=uokmrxukcerwszzc.o From: ;tag=63812e13-00c1-4115-861d-2b897866ab02 Call-ID: 7f4b5f822346cfdb780d3f1162c550ed@55.66.77.88:6666~o~o CSeq: 30656 BYE Server: Sippy Content-Length: 0 [2018-09-29 13:50:17] VERBOSE[26687][C-00000000] pbx.c: Spawn extension (ivr-11, s, 11) exited non-zero on 'PJSIP/6475558888-pjsip-00000000' [2018-09-29 13:50:17] VERBOSE[26687][C-00000000] pbx.c: Executing [h@ivr-11:1] Hangup("PJSIP/6475558888-pjsip-00000000", "") in new stack [2018-09-29 13:50:17] VERBOSE[26687][C-00000000] pbx.c: Spawn extension (ivr-11, h, 1) exited non-zero on 'PJSIP/6475558888-pjsip-00000000'