Sometimes channels do not hangup

Problem appears even on local (not Local/)
I use PJSIP and asterisk 13.5.0.

How could I find the cause of this problem?
It appears randomly…

Can you explain more???! How do you determine this issue? Do you check the logs anymore when it happens?

Hello!

There is really no much info from logs.
For example I see:
core show channels verbose
`Channel Context Extension Prio State Application Data CallerID Duration Accountcode PeerAccount BridgeID

Local/[email protected] from-queue 1000 1 Up AppQueue (Outgoing Line) 101 03:55:53 7414d1b1-8406-4ee1-8
Local/[email protected] macro-dial-one s 47 Up Dial PJSIP/13/sip:[email protected] 9150000000 03:55:53 ba73f0a3-5ab3-4e40-b
`

phone 101 shows no activity in web interface.
So, logs I see that channel 101 and 9150000000 joined to bridge at ~10:00. and nothing more.

I do “channel request hangup PJSIP/101-00010b86” and I see in logs that now channel was hung up (in ~14:00).

Nick, none of that was from /var/log/asterisk/full, which is where one would get the details of what happened at 10:00.

Ok here are logs for stuck channels. I think that there is no vital information
`[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:1] Answer(“PJSIP/ITEL-00010673”, “”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:2] Macro(“PJSIP/ITEL-00010673”, “callworktime,openb,s,000080”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:1] Answer(“PJSIP/ITEL-00010673”, “”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:2] GotoIfTime(“PJSIP/ITEL-00010673”, “09:00-23:00,wed,30,Dec?openb,s,1”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:3] GotoIfTime(“PJSIP/ITEL-00010673”, “,,31,Dec?dec31”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:4] GotoIfTime(“PJSIP/ITEL-00010673”, “,,1,Jan?offline”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:5] GotoIfTime(“PJSIP/ITEL-00010673”, “,,7,Jan?jan7”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:6] GotoIfTime(“PJSIP/ITEL-00010673”, “09:00-21:00,mon,,?openb,s,1”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:7] GotoIfTime(“PJSIP/ITEL-00010673”, “09:00-21:00,tue,,?openb,s,1”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:8] GotoIfTime(“PJSIP/ITEL-00010673”, “09:00-21:00,wed,,?openb,s,1”) in new stack
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] pbx.c: Goto (openb,s,1)
[2016-04-20 11:03:07] VERBOSE[7158][C-00009ac2] app_macro.c: Channel ‘PJSIP/ITEL-00010673’ jumping out of macro ‘callworktime’

got to cut it because of limit

[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:40] Set(“PJSIP/ITEL-00010673”, “__QCALLID=1461139385.93977”) in new stack
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:41] Set(“PJSIP/ITEL-00010673”, “QUEUEJOINTIME=1461139398”) in new stack
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:42] Queue(“PJSIP/ITEL-00010673”, “1000,t,1,”) in new stack
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] res_musiconhold.c: Started music on hold, class ‘m1’, on channel ‘PJSIP/ITEL-00010673’
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “QAGENT=101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] app_queue.c: Called Local/[email protected]/n
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 5
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Goto(“Local/[email protected];2”, “1000,1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (from-queue,1000,1)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Goto(“Local/[email protected];2”, “from-internal,101,1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (from-internal,101,1)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?ext-local,101,1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (ext-local,101,1)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “__RINGTIMER=15”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Macro(“Local/[email protected];2”, “exten-vm,novm,101,0,0,0”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “CHANNEL(language)=en”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “RingGroupMethod=none”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] Set(“Local/[email protected];2”, “__EXTTOCALL=101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] Set(“Local/[email protected];2”, “__PICKUPMARK=101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “RT=”) in new stack
[2016-04-20 11:03:18] WARNING[7226][C-00009ac2] chan_sip.c: This function can only be used on SIP channels.
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2016-04-20 11:03:18] WARNING[7226][C-00009ac2] chan_sip.c: This function can only be used on SIP channels.
[2016-04-20 11:03:18] WARNING[7226][C-00009ac2] chan_sip.c: This function can only be used on SIP channels.
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:7] ExecIf(“Local/[email protected];2”, “0?MacroExit()”) in new stack
[2016-04-20 11:03:18] WARNING[7226][C-00009ac2] chan_sip.c: This function can only be used on SIP channels.
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] Gosub(“Local/[email protected];2”, “sub-record-check,s,1(exten,101,dontcare)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “10?initialized”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,s,10)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:10] NoOp(“Local/[email protected];2”, “Recordings initialized”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:11] ExecIf(“Local/[email protected];2”, “0?Set(ARG3=dontcare)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:12] Set(“Local/[email protected];2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:13] ExecIf(“Local/[email protected];2”, “0?Set(REC_STATUS=NO)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:14] GotoIf(“Local/[email protected];2”, “5?checkaction”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,s,17)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?sub-record-check,exten,1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,exten,1)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Exten Recording Check between 915000000 and 101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “CALLTYPE=internal”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Set(CALLTYPE=)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] Set(“Local/[email protected];2”, “CALLEE=force”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] ExecIf(“Local/[email protected];2”, “0?Set(CALLEE=dontcare)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] GotoIf(“Local/[email protected];2”, “0?callee”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “0?caller”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] ExecIf(“Local/[email protected];2”, “0?Set(CALLER_PRI=):Set(CALLER_PRI=0)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:9] ExecIf(“Local/[email protected];2”, “2?Set(CALLEE_PRI=10):Set(CALLEE_PRI=0)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:10] GotoIf(“Local/[email protected];2”, “0?caller:callee”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,exten,11)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:11] Gosub(“Local/[email protected];2”, “recordcheck,1(force,internal,101)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Starting recording check against force”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Goto(“Local/[email protected];2”, “force”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,recordcheck,5)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “__REC_POLICY_MODE=FORCE”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] GotoIf(“Local/[email protected];2”, “1?startrec”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (sub-record-check,recordcheck,16)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:16] NoOp(“Local/[email protected];2”, “Starting recording: internal, 101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:17] Set(“Local/[email protected];2”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:18] Set(“Local/[email protected];2”, “__CALLFILENAME=internal-101-915000000-20160420-110307-1461139398.93980”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:19] MixMonitor(“Local/[email protected];2”, “2016/04/20/internal-101-915000000-20160420-110307-1461139398.93980.wav,ai(LOCAL_MIXMON_ID),”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:20] Set(“Local/[email protected];2”, “__MIXMON_ID=0xb0c4d0e8”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:21] Set(“Local/[email protected];2”, “__RECORD_ID=Local/[email protected];2”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:22] Set(“Local/[email protected];2”, “__REC_STATUS=RECORDING”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:23] Set(“Local/[email protected];2”, “CDR(recordingfile)=internal-101-915000000-20160420-110307-1461139398.93980.wav”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:24] Return(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:12] Return(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “1?macrodial”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-exten-vm,s,15)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:15] GosubIf(“Local/[email protected];2”, “0?clrheader,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:16] Macro(“Local/[email protected];2”, “dial-one,TtrM(auto-blkvm),101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7227][C-00009ac2] app_mixmonitor.c: Begin MixMonitor Recording Local/[email protected];2
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “DEXTEN=101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “DIALSTATUS_CW=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?screen,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] GosubIf(“Local/[email protected];2”, “0?cf,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skip1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,8)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:10] Set(“Local/[email protected];2”, “EXTHASCW=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “1?next1:cwinusebusy”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,12)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:12] GotoIf(“Local/[email protected];2”, “0?docfu:skip3”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,16)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:16] GotoIf(“Local/[email protected];2”, “1?next2:continue”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,17)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,25)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:25] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:26] GosubIf(“Local/[email protected];2”, “1?dstring,1():dlocal,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “DSTRING=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “DEVICES=101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(DEVICES=364)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “LOOPCNT=1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] Set(“Local/[email protected]e-00003453;2”, “ITER=1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected];2”, “THISDIAL=PJSIP/101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “1?zap2dahdi,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “NEWDIAL=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] Set(“Local/[email protected];2”, “LOOPCNT2=1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] Set(“Local/[email protected];2”, “ITER2=1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “THISPART2=PJSIP/101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(THISPART2=DAHDIIP/101)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected];2”, “NEWDIAL=PJSIP/101&”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] Set(“Local/[email protected];2”, “ITER2=2”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?begin2”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:10] Set(“Local/[email protected];2”, “THISDIAL=PJSIP/101”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:11] Return(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?docheck”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:10] NoOp(“Local/[email protected];2”, “Debug: Found PJSIP Destination PJSIP/101, updating with PJSIP_DIAL_CONTACTS”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:11] Set(“Local/[email protected];2”, “THISDIAL=PJSIP/101/sip:[email protected]:5060”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:12] GotoIf(“Local/[email protected];2”, “0?skipset”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:13] Set(“Local/[email protected];2”, “DSTRING=PJSIP/101/sip:[email protected]:5060&”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:14] Set(“Local/[email protected];2”, “ITER=2”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:15] GotoIf(“Local/[email protected];2”, “0?begin”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:16] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:17] Set(“Local/[email protected];2”, “DSTRING=PJSIP/101/sip:[email protected]:5060”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:18] Return(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:27] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:28] GotoIf(“Local/[email protected];2”, “0?skiptrace”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:29] GosubIf(“Local/[email protected];2”, “1?ctset,1():ctclear,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “DB(CALLTRACE/101)=915000000”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Return(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:30] Set(“Local/[email protected];2”, “D_OPTIONS=TtrM(auto-blkvm)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:31] NoOp(“Local/[email protected];2”, "Blind Transfer: , Attended Transfer: , User: 915000000, Alert Info: ") in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:32] ExecIf(“Local/[email protected];2”, “1?Set(ALERT_INFO=)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:33] ExecIf(“Local/[email protected];2”, “0?Set(ALERT_INFO=)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:34] ExecIf(“Local/[email protected];2”, “0?Set(ALERT_INFO=)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:35] GosubIf(“Local/[email protected];2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:36] ExecIf(“Local/[email protected];2”, “1?Set(CHANNEL(musicclass)=m1)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:37] GosubIf(“Local/[email protected];2”, “0?qwait,1()”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:38] Set(“Local/[email protected];2”, “__CWIGNORE=TRUE”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:39] Set(“Local/[email protected];2”, “__KEEPCID=TRUE”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:40] GotoIf(“Local/[email protected];2”, “0?usegoto,1”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:41] GotoIf(“Local/[email protected];2”, “1?godial”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-dial-one,s,46)
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:46] Macro(“Local/[email protected];2”, “dialout-one-predial-hook,”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] MacroExit(“Local/[email protected];2”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:47] Dial(“Local/[email protected];2”, “PJSIP/101/sip:[email protected]:5060,TtrM(auto-blkvm)b(func-apply-sipheaders^s^1)”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_stack.c: PJSIP/101-00010675 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] NoOp(“PJSIP/101-00010675”, “Applying SIP Headers to channel”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“PJSIP/101-00010675”, “SIPHEADERKEYS=”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] While(“PJSIP/101-00010675”, “0”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_while.c: Jumping to priority 7
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] Return(“PJSIP/101-00010675”, “”) in new stack
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_stack.c: Spawn extension (av-out, 101, 1) exited non-zero on ‘PJSIP/101-00010675’
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_stack.c: PJSIP/101-00010675 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_dial.c: Called PJSIP/101/sip:[email protected]:5060
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] app_queue.c: Local/[email protected];1 is ringing
[2016-04-20 11:03:18] VERBOSE[7226][C-00009ac2] app_dial.c: PJSIP/101-00010675 is ringing
[2016-04-20 11:03:18] VERBOSE[7158][C-00009ac2] app_queue.c: Local/[email protected];1 is ringing
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] app_dial.c: PJSIP/101-00010675 answered Local/[email protected];2
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“PJSIP/101-00010675”, “__MACRO_RESULT=”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“PJSIP/101-00010675”, “CFIGNORE=”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] Set(“PJSIP/101-00010675”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] Set(“PJSIP/101-00010675”, “FORWARD_CONTEXT=from-internal”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:5] Set(“PJSIP/101-00010675”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:6] Macro(“PJSIP/101-00010675”, “blkvm-clr,”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] Set(“PJSIP/101-00010675”, “SHARED(BLKVM,PJSIP/ITEL-00010673)=”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] Set(“PJSIP/101-00010675”, “GOSUB_RETVAL=”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] MacroExit(“PJSIP/101-00010675”, “”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:7] ExecIf(“PJSIP/101-00010675”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=101/sip:[email protected]:5060)”) in new stack
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:8] ExecIf(“PJSIP/101-00010675”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)”) in new stack
[2016-04-20 11:03:21] VERBOSE[7158][C-00009ac2] app_queue.c: Local/[email protected];1 answered PJSIP/ITEL-00010673
[2016-04-20 11:03:21] VERBOSE[7158][C-00009ac2] res_musiconhold.c: Stopped music on hold on PJSIP/ITEL-00010673
[2016-04-20 11:03:21] VERBOSE[7283][C-00009ac2] bridge_channel.c: Channel PJSIP/101-00010675 joined ‘simple_bridge’ basic-bridge <1b70e10f-3746-4ea0-a8d0-c8dcb6b1c64d>
[2016-04-20 11:03:21] VERBOSE[7226][C-00009ac2] bridge_channel.c: Channel Local/[email protected];2 joined ‘simple_bridge’ basic-bridge <1b70e10f-3746-4ea0-a8d0-c8dcb6b1c64d>
[2016-04-20 11:03:21] VERBOSE[7284][C-00009ac2] bridge_channel.c: Channel Local/[email protected];1 joined ‘simple_bridge’ basic-bridge <1d9e9ac4-f92e-4ff6-a286-77fdcf6aff5c>
[2016-04-20 11:03:21] VERBOSE[7158][C-00009ac2] bridge_channel.c: Channel PJSIP/ITEL-00010673 joined ‘simple_bridge’ basic-bridge <1d9e9ac4-f92e-4ff6-a286-77fdcf6aff5c>
Here channel got stuck…

Here I did “channel request hangup”
[2016-04-20 14:11:56] VERBOSE[7283][C-00009ac2] bridge_channel.c: Channel PJSIP/101-00010675 left ‘simple_bridge’ basic-bridge <1b70e10f-3746-4ea0-a8d0-c8dcb6b1c64d>
[2016-04-20 14:11:56] VERBOSE[7226][C-00009ac2] bridge_channel.c: Channel Local/[email protected];2 left ‘simple_bridge’ basic-bridge <1b70e10f-3746-4ea0-a8d0-c8dcb6b1c64d>
[2016-04-20 14:11:56] VERBOSE[7226][C-00009ac2] app_macro.c: Spawn extension (macro-dial-one, s, 47) exited non-zero on ‘Local/[email protected];2’ in macro ‘dial-one’
[2016-04-20 14:11:56] VERBOSE[7226][C-00009ac2] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on ‘Local/[email protected];2’ in macro ‘exten-vm’
[2016-04-20 14:11:56] VERBOSE[7226][C-00009ac2] pbx.c: Spawn extension (ext-local, 101, 2) exited non-zero on ‘Local/[email protected];2’
[2016-04-20 14:11:56] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] System(“Local/[email protected];2”, “sleep 2”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:2] GotoIf(“Local/[email protected];2”, “0?qstats”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] Macro(“Local/[email protected];2”, “hangupcall,”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?theend”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Goto (macro-hangupcall,s,3)
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Executing [[email protected]:4] Hangup(“Local/[email protected];2”, “”) in new stack
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘Local/[email protected];2’ in macro ‘hangupcall’
[2016-04-20 14:11:58] VERBOSE[7226][C-00009ac2] pbx.c: Spawn extension (ext-local, h, 3) exited non-zero on ‘Local/[email protected];2’
[2016-04-20 14:11:58] VERBOSE[7284][C-00009ac2] bridge_channel.c: Channel Local/[email protected];1 left ‘simple_bridge’ basic-bridge <1d9e9ac4-f92e-4ff6-a286-77fdcf6aff5c>
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] bridge_channel.c: Channel PJSIP/ITEL-00010673 left ‘simple_bridge’ basic-bridge <1d9e9ac4-f92e-4ff6-a286-77fdcf6aff5c>
[2016-04-20 14:11:58] VERBOSE[7227][C-00009ac2] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Spawn extension (ext-queues, 1000, 42) exited non-zero on ‘PJSIP/ITEL-00010673’
[2016-04-20 14:11:58] VERBOSE[7227][C-00009ac2] app_mixmonitor.c: End MixMonitor Recording Local/[email protected];2
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:1] Macro(“PJSIP/ITEL-00010673”, “hangupcall,”) in new stack
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:1] GotoIf(“PJSIP/ITEL-00010673”, “1?theend”) in new stack
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Goto (macro-hangupcall,s,3)
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:3] ExecIf(“PJSIP/ITEL-00010673”, “0?Set(CDR(recordingfile)=)”) in new stack
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Executing [[email protected]:4] Hangup(“PJSIP/ITEL-00010673”, “”) in new stack
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/ITEL-00010673’ in macro ‘hangupcall’
[2016-04-20 14:11:58] VERBOSE[7158][C-00009ac2] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on ‘PJSIP/ITEL-00010673’
`