Hey Everyone,
I am having an issue with my install that may be related to nat but I am not sure
When I make inbound calls to my SipStation Trunk I get no audio in either direction.
If I call out to that same number from the phone system audio works in both directions. I am assuming I have a nat issue but this was working just up until an hour or two ago after I had to reboot a router.
I have checked all my nat configs in the system as well as double-checked IP addresses and
I have a second trunk set up using a google voice module that works without issue but alas that only supports one call at a time.
If you look at the following logs at the 9500 section the system appears to connect and play the into file but it is not receiving anything back from the trunk and eventually times out.
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-sip-external:1] NoOp(“SIP/192.159.66.3-00000015”, “Received incoming SIP connection from unknown peer to 8457580104”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-sip-external:2] Set(“SIP/192.159.66.3-00000015”, “DID=8457580104”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-sip-external:3] Goto(“SIP/192.159.66.3-00000015”, “s,1”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (from-sip-external,s,1)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@from-sip-external:1] GotoIf(“SIP/192.159.66.3-00000015”, “1?setlanguage:checkanon”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (from-sip-external,s,2)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@from-sip-external:2] Set(“SIP/192.159.66.3-00000015”, “CHANNEL(language)=en”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@from-sip-external:3] GotoIf(“SIP/192.159.66.3-00000015”, “0?noanonymous”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@from-sip-external:4] Goto(“SIP/192.159.66.3-00000015”, “from-trunk,8457580104,1”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (from-trunk,8457580104,1)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:1] Set(“SIP/192.159.66.3-00000015”, “__DIRECTION=INBOUND”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:2] Gosub(“SIP/192.159.66.3-00000015”, “sub-record-check,s,1(in,8457580104,dontcare)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/192.159.66.3-00000015”, “0?initialized”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/192.159.66.3-00000015”, “__REC_STATUS=INITIALIZED”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/192.159.66.3-00000015”, “NOW=1508886753”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/192.159.66.3-00000015”, “__DAY=24”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/192.159.66.3-00000015”, “__MONTH=10”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/192.159.66.3-00000015”, “__YEAR=2017”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/192.159.66.3-00000015”, “__TIMESTR=20171024-191233”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/192.159.66.3-00000015”, “__FROMEXTEN=unknown”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/192.159.66.3-00000015”, “__MON_FMT=wav”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/192.159.66.3-00000015”, “Recordings initialized”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/192.159.66.3-00000015”, “0?Set(ARG3=dontcare)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/192.159.66.3-00000015”, “REC_POLICY_MODE_SAVE=”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/192.159.66.3-00000015”, “0?Set(REC_STATUS=NO)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/192.159.66.3-00000015”, “2?checkaction”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/192.159.66.3-00000015”, “1?sub-record-check,in,1”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (sub-record-check,in,1)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/192.159.66.3-00000015”, “Inbound Recording Check to 8457580104”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/192.159.66.3-00000015”, “FROMEXTEN=unknown”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/192.159.66.3-00000015”, “11?Set(FROMEXTEN=18457580107)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/192.159.66.3-00000015”, “recordcheck,1(dontcare,in,8457580104)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/192.159.66.3-00000015”, “Starting recording check against dontcare”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/192.159.66.3-00000015”, “dontcare”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:3] Gosub(“SIP/192.159.66.3-00000015”, “app-blacklist-check,s,1()”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/192.159.66.3-00000015”, “0?blacklisted”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/192.159.66.3-00000015”, “CALLED_BLACKLIST=1”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:4] Set(“SIP/192.159.66.3-00000015”, “__FROM_DID=8457580104”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:5] Set(“SIP/192.159.66.3-00000015”, “CDR(did)=8457580104”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:6] ExecIf(“SIP/192.159.66.3-00000015”, “0 ?Set(CALLERID(name)=18457580107)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:7] Set(“SIP/192.159.66.3-00000015”, “__MOHCLASS=”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:8] Set(“SIP/192.159.66.3-00000015”, “__REVERSAL_REJECT=FALSE”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:9] GotoIf(“SIP/192.159.66.3-00000015”, “1?post-reverse-charge”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (from-trunk,8457580104,11)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:11] NoOp(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:12] Set(“SIP/192.159.66.3-00000015”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:13] Set(“SIP/192.159.66.3-00000015”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:14] Set(“SIP/192.159.66.3-00000015”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:15] Set(“SIP/192.159.66.3-00000015”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:16] NoOp(“SIP/192.159.66.3-00000015”, “CallerID Entry Point”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:17] Set(“SIP/192.159.66.3-00000015”, “__CRM_DIRECTION=INBOUND”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:18] Set(“SIP/192.159.66.3-00000015”, “__CRM_SOURCE=18457580107”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:19] Set(“SIP/192.159.66.3-00000015”, “__CRM_LINKEDID=1508886752.23”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:20] ExecIf(“SIP/192.159.66.3-00000015”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [8457580104@from-trunk:21] Goto(“SIP/192.159.66.3-00000015”, “app-announcement-1,s,1”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (app-announcement-1,s,1)
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:1] GotoIf(“SIP/192.159.66.3-00000015”, “0?begin”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:2] Answer(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:12:33] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:3] Wait(“SIP/192.159.66.3-00000015”, “1”) in new stack
[2017-10-24 19:12:34] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:4] NoOp(“SIP/192.159.66.3-00000015”, “Playing announcement Company Intro(Change for Meeting)”) in new stack
[2017-10-24 19:12:34] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:5] BackGround(“SIP/192.159.66.3-00000015”, “custom/thanks_for_calling,nm”) in new stack
[2017-10-24 19:12:34] VERBOSE[9500][C-00000011] file.c: <SIP/192.159.66.3-00000015> Playing ‘custom/thanks_for_calling.slin’ (language ‘en’)
[2017-10-24 19:12:39] VERBOSE[9471][C-00000010] bridge_channel.c: Channel SIP/fpbx-1-JbeIf2NHKAhD-00000014 left ‘simple_bridge’ basic-bridge <8143c962-0ee0-47a0-a734-8f1d4411839d>
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] bridge_channel.c: Channel SIP/105-00000013 left ‘simple_bridge’ basic-bridge <8143c962-0ee0-47a0-a734-8f1d4411839d>
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] app_macro.c: Spawn extension (macro-dialout-trunk, s, 30) exited non-zero on ‘SIP/105-00000013’ in macro ‘dialout-trunk’
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Spawn extension (from-internal, 8457580106, 7) exited non-zero on ‘SIP/105-00000013’
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [h@from-internal:1] Macro(“SIP/105-00000013”, “hangupcall”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/105-00000013”, “1?theend”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/105-00000013”, “0?Set(CDR(recordingfile)=)”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/105-00000013”, “”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/105-00000013’ in macro ‘hangupcall’
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/105-00000013’
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] app_stack.c: SIP/105-00000013 Internal Gosub(crm-hangup,s,1) start
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/105-00000013”, “Sending Hangup to CRM”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/105-00000013”, “HANGUP CAUSE: 16”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/105-00000013”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/105-00000013”, “MASTER CHANNEL: 1508886740.21 = 1508886740.21”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/105-00000013”, “0?return”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/105-00000013”, “__CRM_HANGUP=1”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/105-00000013”, “sangomacrm.agi”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] res_agi.c: <SIP/105-00000013>AGI Script sangomacrm.agi completed, returning 0
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/105-00000013”, “”) in new stack
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/105-00000013’
[2017-10-24 19:12:39] VERBOSE[9456][C-00000010] app_stack.c: SIP/105-00000013 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2017-10-24 19:12:39] VERBOSE[2221] chan_sip.c: Extension Changed 105[ext-local] new state Idle for Notify User 105
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@app-announcement-1:6] GotoIf(“SIP/192.159.66.3-00000015”, “1?ivr-1,s,1:,return,1”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (ivr-1,s,1)
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:1] Set(“SIP/192.159.66.3-00000015”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:2] Set(“SIP/192.159.66.3-00000015”, “INVALID_LOOPCOUNT=0”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:3] Set(“SIP/192.159.66.3-00000015”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:4] Set(“SIP/192.159.66.3-00000015”, “_IVR_CONTEXT=ivr-1”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:5] Set(“SIP/192.159.66.3-00000015”, “__IVR_RETVM=”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:6] GotoIf(“SIP/192.159.66.3-00000015”, “1?skip”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx_builtins.c: Goto (ivr-1,s,9)
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:9] Set(“SIP/192.159.66.3-00000015”, “IVR_MSG=custom/all-options”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:10] Set(“SIP/192.159.66.3-00000015”, “TIMEOUT(digit)=3”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] func_timeout.c: Digit timeout set to 3.000
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/192.159.66.3-00000015”, “1?Background(custom/all-options)”) in new stack
[2017-10-24 19:12:41] VERBOSE[9500][C-00000011] file.c: <SIP/192.159.66.3-00000015> Playing ‘custom/all-options.slin’ (language ‘en’)
[2017-10-24 19:12:56] VERBOSE[9500][C-00000011] pbx.c: Executing [s@ivr-1:12] WaitExten(“SIP/192.159.66.3-00000015”, “10,”) in new stack
[2017-10-24 19:13:04] NOTICE[2337] chan_sip.c: Disconnecting call ‘SIP/192.159.66.3-00000015’ for lack of RTP activity in 31 seconds
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Spawn extension (ivr-1, s, 12) exited non-zero on ‘SIP/192.159.66.3-00000015’
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [h@ivr-1:1] Hangup(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/192.159.66.3-00000015’
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] app_stack.c: SIP/192.159.66.3-00000015 Internal Gosub(crm-hangup,s,1) start
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/192.159.66.3-00000015”, “Sending Hangup to CRM”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/192.159.66.3-00000015”, “HANGUP CAUSE: 44”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/192.159.66.3-00000015”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/192.159.66.3-00000015”, “MASTER CHANNEL: 1508886752.23 = 1508886752.23”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/192.159.66.3-00000015”, “0?return”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/192.159.66.3-00000015”, “__CRM_HANGUP=1”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/192.159.66.3-00000015”, “sangomacrm.agi”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] res_agi.c: <SIP/192.159.66.3-00000015>AGI Script sangomacrm.agi completed, returning 0
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/192.159.66.3-00000015”, “”) in new stack
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] app_stack.c: Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/192.159.66.3-00000015’
[2017-10-24 19:13:04] VERBOSE[9500][C-00000011] app_stack.c: SIP/192.159.66.3-00000015 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2017-10-24 19:13:05] WARNING[2337] chan_sip.c: Retransmission timeout reached on transmission a8089f64-33b3-1236-328c-c81f66c921dc for seqno 114107312 (Critical Response) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response