Hopefully I can provide you all the info to explain where I am in troubleshooting and what I am seeing… so here we go.
FreePBX 12.7.5-1807
i7, 16GB, SSD drive
System will make an outgoing phone call with no issues, incoming calls drop AUDIO after 15-15.5 min. The call does not hangup or disconnect, the audio quits working. If you stay on the call, you can sometimes hear yourself like an echo, but no audio transmission in either direction.
I have a SIP trunk thru the ISP. CHANSIP on the trunk running standard ports. Internal devices are PJSIP on 5160.
I have tried the following:
Adjusted RTP Timeout
RTP Hold Timeout
CHANSIP set for NAT:yes, Static IP
Adjusted registration max and min expiry
CHANSIP other setting: session-timers = refuse
Here are SIP trunk settings (of which the ISP has been NO help)
Outgoing Peer Details:
host=
type=friend
fromuser=
dtmfmode=rfc2833
port=5060
directmedia=no
canreinvite=yes
insecure=port,invite
disallow=all
allow=ulaw
Incoming User Context:
Incoming User Details:
host=
type=friend
nat=never
disallow=all
canreinvite=yes
allow=ulaw
dtmfmode=rfc2833
context=from-trunk
insecure=port,invite
qualify=yes
I am inserting a test call log here (IP, phone numbers masked)
I called in, transferred to a test extension, placed on hold and listened to music. Music stopped at 15min
The firewall is managed by the ISP, and if there is something specific I can direct them to look at, please let me know.
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/704#######-0000007b", "10?initialized") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,s,10)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/704#######-0000007b", "Recordings initialized") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/704#######-0000007b", "0?Set(ARG3=dontcare)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:12] Set("SIP/704#######-0000007b", "REC_POLICY_MODE_SAVE=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/704#######-0000007b", "0?Set(REC_STATUS=NO)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/704#######-0000007b", "5?checkaction") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/704#######-0000007b", "1?sub-record-check,exten,1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/704#######-0000007b", "Exten Recording Check between 704####### and 1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/704#######-0000007b", "CALLTYPE=external") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/704#######-0000007b", "0?Set(CALLTYPE=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/704#######-0000007b", "CALLEE=dontcare") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/704#######-0000007b", "0?Set(CALLEE=dontcare)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/704#######-0000007b", "1?callee") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/704#######-0000007b", "recordcheck,1(dontcare,external,1191)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/704#######-0000007b", "Exten Recording Check between 704####### and 1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/704#######-0000007b", "CALLTYPE=external") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/704#######-0000007b", "0?Set(CALLTYPE=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/704#######-0000007b", "CALLEE=dontcare") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/704#######-0000007b", "0?Set(CALLEE=dontcare)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/704#######-0000007b", "1?callee") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/704#######-0000007b", "recordcheck,1(dontcare,external,1191)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/704#######-0000007b", "Starting recording check against dontcare") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/704#######-0000007b", "dontcare") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/704#######-0000007b", "1?macrodial") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("SIP/704#######-0000007b", "0?clrheader,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-exten-vm:26] Macro("SIP/704#######-0000007b", "dial-one,,HhTtr,1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/704#######-0000007b", "DEXTEN=1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:2] ExecIf("SIP/704#######-0000007b", "0?Set(__EXTTOCALL=1191)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:3] Set("SIP/704#######-0000007b", "DIALSTATUS_CW=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:4] GosubIf("SIP/704#######-0000007b", "0?screen,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/704#######-0000007b", "0?cf,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:6] GotoIf("SIP/704#######-0000007b", "1?skip1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:9] GotoIf("SIP/704#######-0000007b", "0?nodial") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/704#######-0000007b", "0?continue") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:11] Set("SIP/704#######-0000007b", "EXTHASCW=ENABLED") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/704#######-0000007b", "0?next1:cwinusebusy") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:24] GotoIf("SIP/704#######-0000007b", "0?next3:continue") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/704#######-0000007b", "0?nodial") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:27] GosubIf("SIP/704#######-0000007b", "1?dstring,1():dlocal,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/704#######-0000007b", "DSTRING=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/704#######-0000007b", "DEVICES=1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/704#######-0000007b", "0?Return()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/704#######-0000007b", "0?Set(DEVICES=191)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/704#######-0000007b", "LOOPCNT=1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/704#######-0000007b", "ITER=1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/704#######-0000007b", "THISDIAL=PJSIP/1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/704#######-0000007b", "1?zap2dahdi,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/704#######-0000007b", "0?Return()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/704#######-0000007b", "NEWDIAL=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/704#######-0000007b", "LOOPCNT2=1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/704#######-0000007b", "ITER2=1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/704#######-0000007b", "THISPART2=PJSIP/1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/704#######-0000007b", "0?Set(THISPART2=DAHDIIP/1191)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/704#######-0000007b", "NEWDIAL=PJSIP/1191&") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/704#######-0000007b", "ITER2=2") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/704#######-0000007b", "0?begin2") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/704#######-0000007b", "THISDIAL=PJSIP/1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/704#######-0000007b", "0?docheck") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("SIP/704#######-0000007b", "Debug: Found PJSIP Destination PJSIP/1191") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("SIP/704#######-0000007b", "0?doset") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("SIP/704#######-0000007b", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:13] Set("SIP/704#######-0000007b", "THISDIAL=PJSIP/1191/sip:[email protected]:56334;rinstance=490683265c18c205") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("SIP/704#######-0000007b", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("SIP/704#######-0000007b", "0?skipset") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/704#######-0000007b", "DSTRING=PJSIP/1191/sip:[email protected]:56334;rinstance=490683265c18c205&") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:17] Set("SIP/704#######-0000007b", "ITER=2") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("SIP/704#######-0000007b", "0?begin") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("SIP/704#######-0000007b", "0?Return()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:20] Set("SIP/704#######-0000007b", "DSTRING=PJSIP/1191/sip:[email protected]:56334;rinstance=490683265c18c205") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [dstring@macro-dial-one:21] Return("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/704#######-0000007b", "0?nodial") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/704#######-0000007b", "0?skiptrace") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:30] GosubIf("SIP/704#######-0000007b", "1?ctset,1():ctclear,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/704#######-0000007b", "DB(CALLTRACE/1191)=704#######") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:31] Set("SIP/704#######-0000007b", "D_OPTIONS=HhTtr") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:32] GosubIf("SIP/704#######-0000007b", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:33] NoOp("SIP/704#######-0000007b", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:34] ExecIf("SIP/704#######-0000007b", "0?Set(ALERT_INFO=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:35] ExecIf("SIP/704#######-0000007b", "0?Set(ALERT_INFO=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/704#######-0000007b", "0?Set(ALERT_INFO=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/704#######-0000007b", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/704#######-0000007b", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:39] GosubIf("SIP/704#######-0000007b", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/704#######-0000007b", "0?Set(CHANNEL(musicclass)=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:41] GosubIf("SIP/704#######-0000007b", "0?qwait,1()") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:42] Set("SIP/704#######-0000007b", "__CWIGNORE=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:43] Set("SIP/704#######-0000007b", "__KEEPCID=TRUE") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:44] GotoIf("SIP/704#######-0000007b", "0?usegoto,1") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:45] GotoIf("SIP/704#######-0000007b", "1?godial") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx_builtins.c: Goto (macro-dial-one,s,50)
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:50] Macro("SIP/704#######-0000007b", "dialout-one-predial-hook,") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:51] ExecIf("SIP/704#######-0000007b", "1?Set(D_OPTIONS=HhtrI)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:52] NoOp("SIP/704#######-0000007b", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:53] ExecIf("SIP/704#######-0000007b", "0?Set(D_OPTIONS=HhtrIg)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@macro-dial-one:54] Dial("SIP/704#######-0000007b", "PJSIP/1191/sip:[email protected]:56334;rinstance=490683265c18c205,,HhtrIb(func-apply-sipheaders^s^1)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_stack.c: PJSIP/1191-00000284 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/1191-00000284", "Applying SIP Headers to channel ") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/1191-00000284", "SIPHEADERKEYS=") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:3] ExecIf("PJSIP/1191-00000284", "0?Set(Rheader=1)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:4] While("PJSIP/1191-00000284", "0") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_while.c: Jumping to priority 8
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("PJSIP/1191-00000284", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/1191-00000284", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] pbx.c: Executing [s@func-apply-sipheaders:11] Return("PJSIP/1191-00000284", "") in new stack
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_stack.c: Spawn extension (from-internal, 1191, 1) exited non-zero on 'PJSIP/1191-00000284'
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_stack.c: PJSIP/1191-00000284 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_dial.c: Called PJSIP/1191/sip:[email protected]:56334;rinstance=490683265c18c205
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Video TOS bits 136
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Video TOS bits 136 in TCLASS field.
[2019-01-14 16:56:16] VERBOSE[26668] netsock2.c: Using SIP RTP Video CoS mark 4
[2019-01-14 16:56:16] VERBOSE[5247][C-00000221] app_dial.c: Connected line update to SIP/704#######-0000007b prevented.
[2019-01-14 16:56:17] VERBOSE[5247][C-00000221] app_dial.c: PJSIP/1191-00000284 is ringing
[2019-01-14 16:56:17] VERBOSE[5247][C-00000221] app_dial.c: PJSIP/1191-00000284 is ringing
[2019-01-14 16:56:20] VERBOSE[5247][C-00000221] app_dial.c: PJSIP/1191-00000284 answered SIP/704#######-0000007b
[2019-01-14 16:56:20] VERBOSE[5248][C-00000221] bridge_channel.c: Channel PJSIP/1191-00000284 joined 'simple_bridge' basic-bridge <382b6e2b-2265-4004-b5a5-d5ea1b69e220>
[2019-01-14 16:56:20] VERBOSE[5247][C-00000221] bridge_channel.c: Channel SIP/704#######-0000007b joined 'simple_bridge' basic-bridge <382b6e2b-2265-4004-b5a5-d5ea1b69e220>
[2019-01-14 16:56:23] VERBOSE[5247][C-00000221] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/704#######-0000007b'
[2019-01-14 17:11:28] VERBOSE[5247][C-00000221] res_musiconhold.c: Stopped music on hold on SIP/704#######-0000007b ```