Hangup and MOH on incoming calls

We are experiencing hangups on all incoming calls, the outbound calls are working perfectly. This problem occurred this morning. I have checked and this does not affect the internal calls. We can also receive calls using our second SIP trunk, so I suppose it is not a problem with the SIP configuration and the firewall. Yesterday and the days before that, everything worked fine. It is strange because not every incoming calls is affected by hangups but the majority of them are. The asterisk version is 11.10.2 and freepbx: 2.11.0.37.

I am using Users and Devices and I have attached two screenshots of their settings.

I am also attaching two pcap files on two different calls one successful (on the right) and one unsuccessfull (on the left). And the only difference is that we have 2 SDP invites on the left and one on the right. Is this somehow connected to the reinvite option?

The call log is quite long so that is why I am posting it here. Please keep in mind that I have replaced the numbers with NUMBER and the names of the persons in the call log:

[2014-07-23 12:30:37] VERBOSE[1874][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2014-07-23 12:30:37] VERBOSE[1874][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/SIPTRUNK-0000002b”, “GROUP()=OUT_2”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Goto(“SIP/SIPTRUNK-0000002b”, “from-trunk,NUMBER,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (from-trunk,NUMBER,1)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/SIPTRUNK-0000002b”, “__FROM_DID=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/SIPTRUNK-0000002b”, “app-blacklist-check,s,1()”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?blacklisted”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“SIP/SIPTRUNK-0000002b”, “CALLED_BLACKLIST=1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]cklist-check:3] Return(“SIP/SIPTRUNK-0000002b”, “”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“SIP/SIPTRUNK-0000002b”, “CDR(did)=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/SIPTRUNK-0000002b”, “1 ?Set(CALLERID(name)=NUMBER)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“SIP/SIPTRUNK-0000002b”, “CHANNEL(musicclass)=default”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:6] Set(“SIP/SIPTRUNK-0000002b”, “__MOHCLASS=default”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“SIP/SIPTRUNK-0000002b”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:8] Set(“SIP/SIPTRUNK-0000002b”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:9] Goto(“SIP/SIPTRUNK-0000002b”, “from-did-direct,66,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (from-did-direct,66,1)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?ext-local,66,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Macro(“SIP/SIPTRUNK-0000002b”, “user-callerid,”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/SIPTRUNK-0000002b”, “TOUCH_MONITOR=1406111437.85”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“SIP/SIPTRUNK-0000002b”, “AMPUSER=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?report”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/SIPTRUNK-0000002b”, “1?Set(REALCALLERIDNUM=NUMBER)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“SIP/SIPTRUNK-0000002b”, “AMPUSER=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?limit”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“SIP/SIPTRUNK-0000002b”, “AMPUSERCIDNAME=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?report”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (macro-user-callerid,s,16)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:16] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?continue”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:17] ExecIf(“SIP/SIPTRUNK-0000002b”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:18] Set(“SIP/SIPTRUNK-0000002b”, “__TTL=64”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:19] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?continue”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (macro-user-callerid,s,30)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:30] Set(“SIP/SIPTRUNK-0000002b”, “CALLERID(number)=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:31] Set(“SIP/SIPTRUNK-0000002b”, “CALLERID(name)=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:32] Set(“SIP/SIPTRUNK-0000002b”, “CDR(cnum)=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:33] Set(“SIP/SIPTRUNK-0000002b”, “CDR(cnam)=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:34] Set(“SIP/SIPTRUNK-0000002b”, “CHANNEL(language)=en”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“SIP/SIPTRUNK-0000002b”, “DIAL_OPTIONS=TtrI”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“SIP/SIPTRUNK-0000002b”, “CONNECTEDLINE(num)=66”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:5] Gosub(“SIP/SIPTRUNK-0000002b”, “sub-presencestate-display,s,1(66)”) in new stack
[2014-07-23 12:30:37] WARNING[2474][C-00000012] func_presencestate.c: PRESENCE_STATE unknown
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Goto(“SIP/SIPTRUNK-0000002b”, “state-,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (sub-presencestate-display,state-,1)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [state-@sub-presencestate-display:1] Set(“SIP/SIPTRUNK-0000002b”, “PRESENCESTATE_DISPLAY=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [state-@sub-presencestate-display:2] Return(“SIP/SIPTRUNK-0000002b”, “”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:6] Set(“SIP/SIPTRUNK-0000002b”, “CONNECTEDLINE(name,i)=NAME”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“SIP/SIPTRUNK-0000002b”, “FM_DIALSTATUS=NOT_INUSE”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:8] Set(“SIP/SIPTRUNK-0000002b”, “__EXTTOCALL=66”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:9] Set(“SIP/SIPTRUNK-0000002b”, “__PICKUPMARK=66”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:10] Macro(“SIP/SIPTRUNK-0000002b”, “blkvm-setifempty,”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?init”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“SIP/SIPTRUNK-0000002b”, “__BLKVM_CHANNEL=SIP/SIPTRUNK-0000002b”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“SIP/SIPTRUNK-0000002b”, “SHARED(BLKVM,SIP/SIPTRUNK-0000002b)=TRUE”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:6] Set(“SIP/SIPTRUNK-0000002b”, “GOSUB_RETVAL=TRUE”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] MacroExit(“SIP/SIPTRUNK-0000002b”, “”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?skipov”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (from-did-direct,66,14)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:14] Set(“SIP/SIPTRUNK-0000002b”, “RRNODEST=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:15] Set(“SIP/SIPTRUNK-0000002b”, “__NODEST=66”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:16] GosubIf(“SIP/SIPTRUNK-0000002b”, “0?sub-fmsetcid,s,1()”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:17] Set(“SIP/SIPTRUNK-0000002b”, “RecordMethod=Group”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:18] Gosub(“SIP/SIPTRUNK-0000002b”, “sub-record-check,s,1(exten,66,)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/SIPTRUNK-0000002b”, “REC_POLICY_MODE_SAVE=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?check”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (sub-record-check,s,7)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“SIP/SIPTRUNK-0000002b”, “__MON_FMT=wav”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?next”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (sub-record-check,s,11)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/SIPTRUNK-0000002b”, “0?Return()”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/SIPTRUNK-0000002b”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?exten,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:14] Set(“SIP/SIPTRUNK-0000002b”, “__REC_STATUS=INITIALIZED”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:15] Set(“SIP/SIPTRUNK-0000002b”, “NOW=1406111437”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:16] Set(“SIP/SIPTRUNK-0000002b”, “__DAY=23”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:17] Set(“SIP/SIPTRUNK-0000002b”, “__MONTH=07”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:18] Set(“SIP/SIPTRUNK-0000002b”, “__YEAR=2014”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:19] Set(“SIP/SIPTRUNK-0000002b”, “__TIMESTR=20140723-123037”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:20] Set(“SIP/SIPTRUNK-0000002b”, “__FROMEXTEN=NUMBER”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:21] Set(“SIP/SIPTRUNK-0000002b”, “__CALLFILENAME=exten-66-NUMBER-20140723-123037-1406111437.85”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:22] Goto(“SIP/SIPTRUNK-0000002b”, “exten,1”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (sub-record-check,exten,1)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?callee”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“SIP/SIPTRUNK-0000002b”, “__REC_POLICY_MODE=dontcare”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?caller”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Goto (sub-record-check,exten,10)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:10] Set(“SIP/SIPTRUNK-0000002b”, “__REC_POLICY_MODE=”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/SIPTRUNK-0000002b”, “0?record,1(exten,66,NUMBER)”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:12] Return(“SIP/SIPTRUNK-0000002b”, “”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:19] Set(“SIP/SIPTRUNK-0000002b”, “RingGroupMethod=ringallv2-prim”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:20] Set(“SIP/SIPTRUNK-0000002b”, “_FMGRP=66”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:21] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?doconfirm”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:22] Macro(“SIP/SIPTRUNK-0000002b”, “dial,27,TtrI,66-NUMBER#”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “0?dial”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] SetMusicOnHold(“SIP/SIPTRUNK-0000002b”, “default”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] AGI(“SIP/SIPTRUNK-0000002b”, “dialparties.agi”) in new stack
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: dialparties.agi: Caller ID name is ‘NUMBER’ number is ‘NUMBER’
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: dialparties.agi: Methodology of ring is ‘ringallv2-prim’
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Added extension 66 to extension map
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Added extension NUMBER# to extension map
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Extension 66 cf is disabled
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Extension NUMBER# cf is disabled
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Extension 66 do not disturb is disabled
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: dialparties.agi: Extension 66 has ExtensionState: 0
[2014-07-23 12:30:37] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/66 to NUMBER
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] res_agi.c: – dialparties.agi: Filtered ARG3: 66-017624092953
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] res_agi.c: – <SIP/SIPTRUNK-0000002b>AGI Script dialparties.agi completed, returning 0
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] Dial(“SIP/SIPTRUNK-0000002b”, “Local/[email protected]&Local/FMGL-NUMBER#@from-internal,27,TtrIM(auto-blkvm)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Called Local/[email protected]
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Called Local/FMGL-NUMBER#@from-internal
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] NoCDR(“Local/[email protected];2”, “”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RingGroupMethod=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “USE_CONFIRMATION=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:1] NoCDR(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “RINGGROUP_INDEX=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:2] Set(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “ENDLOOP=1406111445”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] Macro(“Local/[email protected];2”, “simple-dial,66,27”) in new stack
[2014-07-23 12:30:38] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:3] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “0?dodnd”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “__EXTTOCALL=66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RT=27”) in new stack
[2014-07-23 12:30:38] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:4] Wait(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “CFUEXT=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “CFBEXT=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “CWI_TMP=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:6] Macro(“Local/[email protected];2”, “dial-one,27,Ttr,66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DEXTEN=66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DIALSTATUS_CW=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?screen,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] GosubIf(“Local/[email protected];2”, “0?cf,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skip1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Goto (macro-dial-one,s,8)
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “EXTHASCW=ENABLED”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?next1:cwinusebusy”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Goto (macro-dial-one,s,23)
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:23] GotoIf(“Local/[email protected];2”, “1?next3:continue”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Goto (macro-dial-one,s,24)
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:24] ExecIf(“Local/[email protected];2”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:25] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:26] GosubIf(“Local/[email protected];2”, “1?dstring,1():dlocal,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DSTRING=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DEVICES=66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(DEVICES=6)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “LOOPCNT=1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “ITER=1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “THISDIAL=SIP/66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “1?zap2dahdi,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “NEWDIAL=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “LOOPCNT2=1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “ITER2=1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “THISPART2=SIP/66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(THISPART2=DAHDI/66)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “NEWDIAL=SIP/66&”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “ITER2=2”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?begin2”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “THISDIAL=SIP/66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:11] Return(“Local/[email protected];2”, “”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:9] Set(“Local/[email protected];2”, “DSTRING=SIP/66&”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “ITER=2”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?begin”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:12] Set(“Local/[email protected];2”, “DSTRING=SIP/66”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:13] Return(“Local/[email protected];2”, “”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:27] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:28] GotoIf(“Local/[email protected];2”, “0?skiptrace”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:29] GosubIf(“Local/[email protected];2”, “1?ctset,1():ctclear,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DB(CALLTRACE/66)=NUMBER”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Return(“Local/[email protected];2”, “”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:30] Set(“Local/[email protected];2”, “D_OPTIONS=TtrM(auto-blkvm)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:31] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:32] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:33] ExecIf(“Local/[email protected];2”, “1?Set(CHANNEL(musicclass)=default)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:34] GosubIf(“Local/[email protected];2”, “0?qwait,1()”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:35] Set(“Local/[email protected];2”, “__CWIGNORE=”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:36] Set(“Local/[email protected];2”, “__KEEPCID=TRUE”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:37] GotoIf(“Local/[email protected];2”, “0?usegoto,1”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:38] GotoIf(“Local/[email protected];2”, “1?godial”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Goto (macro-dial-one,s,43)
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:43]

Dial(“Local/[email protected];2”, “SIP/66,27,TtrM(auto-blkvm)”) in new stack
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] app_dial.c: – Called SIP/66
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Local/[email protected];1 is ringing
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Connected line update to SIP/SIPTRUNK-0000002b prevented.
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Connected line update to SIP/SIPTRUNK-0000002b prevented.
[2014-07-23 12:30:38] VERBOSE[2476][C-00000012] app_dial.c: – SIP/66-0000002c is ringing
[2014-07-23 12:30:38] VERBOSE[2474][C-00000012] app_dial.c: – Local/[email protected];1 is ringing
[2014-07-23 12:30:39] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:5] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1?start”) in new stack
[2014-07-23 12:30:39] VERBOSE[2477][C-00000012] pbx.c: – Goto (from-internal,FMGL-NUMBER#,3)
[2014-07-23 12:30:39] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:3] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “0?dodnd”) in new stack
[2014-07-23 12:30:39] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:4] Wait(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1”) in new stack
[2014-07-23 12:30:40] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:5] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1?start”) in new stack
[2014-07-23 12:30:40] VERBOSE[2477][C-00000012] pbx.c: – Goto (from-internal,FMGL-NUMBER#,3)
[2014-07-23 12:30:40] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:3] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “0?dodnd”) in new stack
[2014-07-23 12:30:40] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:4] Wait(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1”) in new stack
[2014-07-23 12:30:41] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:5] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1?start”) in new stack
[2014-07-23 12:30:41] VERBOSE[2477][C-00000012] pbx.c: – Goto (from-internal,FMGL-NUMBER#,3)
[2014-07-23 12:30:41] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:3] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “0?dodnd”) in new stack
[2014-07-23 12:30:41] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:4] Wait(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1”) in new stack
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:5] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1?start”) in new stack
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: – Goto (from-internal,FMGL-NUMBER#,3)
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:3] GotoIf(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “0?dodnd”) in new stack
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: – Executing [FMGL-NUMBER#@from-internal:4] Wait(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “1”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] app_dial.c: – SIP/66-0000002c answered Local/[email protected];2
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] app_dial.c: – Connected line update to SIP/SIPTRUNK-0000002b prevented.
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/66-0000002c”, “__MACRO_RESULT=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“SIP/66-0000002c”, “CFIGNORE=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“SIP/66-0000002c”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“SIP/66-0000002c”, “FORWARD_CONTEXT=from-internal”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“SIP/66-0000002c”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:6] Macro(“SIP/66-0000002c”, “blkvm-clr,”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“SIP/66-0000002c”, “SHARED(BLKVM,SIP/SIPTRUNK-0000002b)=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“SIP/66-0000002c”, “GOSUB_RETVAL=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] MacroExit(“SIP/66-0000002c”, “”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/66-0000002c”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=66)”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/66-0000002c”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=NAME)”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] app_dial.c: – Local/[email protected];1 answered SIP/SIPTRUNK-0000002b
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: == Spawn extension (from-internal, FMGL-NUMBER#, 4) exited non-zero on ‘Local/FMGL-NUMBER#@from-internal-00000016;2’
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: – Executing [[email protected]:1] Hangup(“Local/FMGL-NUMBER#@from-internal-00000016;2”, “”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];1”, “__MACRO_RESULT=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];1”, “CFIGNORE=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];1”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];1”, “FORWARD_CONTEXT=from-internal”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];1”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2014-07-23 12:30:42] VERBOSE[2477][C-00000012] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/FMGL-NUMBER#@from-internal-00000016;2’
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:6] Macro(“Local/[email protected];1”, “blkvm-clr,”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];1”, “SHARED(BLKVM,SIP/SIPTRUNK-0000002b)=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];1”, “GOSUB_RETVAL=”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] MacroExit(“Local/[email protected];1”, “”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:7] ExecIf(“Local/[email protected];1”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))[email protected])”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:8] ExecIf(“Local/[email protected];1”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)”) in new stack
[2014-07-23 12:30:42] VERBOSE[2474][C-00000012] res_musiconhold.c: – Started music on hold, class ‘default’, on Local/[email protected];1
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] res_musiconhold.c: – Stopped music on hold on SIP/66-0000002c
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/66-0000002c
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] res_musiconhold.c: – Stopped music on hold on SIP/66-0000002c
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/66-0000002c
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “hangupcall,”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?theend”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Goto (macro-hangupcall,s,3)
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [[email protected]:4] Hangup(“Local/[email protected];2”, “”) in new stack
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘Local/[email protected];2’ in macro ‘hangupcall’
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on ‘Local/[email protected];2’
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] app_macro.c: == Spawn extension (macro-dial-one, s, 43) exited non-zero on ‘Local/[email protected];2’ in macro ‘dial-one’
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] app_macro.c: == Spawn extension (macro-simple-dial, s, 6) exited non-zero on ‘Local/[email protected];2’ in macro ‘simple-dial’
[2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: == Spawn extension (from-internal, FMPR-66, 5) exited non-zero on ‘Local/[email protected];2’
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] Macro(“SIP/SIPTRUNK-0000002b”, “hangupcall”) in new stack
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/SIPTRUNK-0000002b”, “1?theend”) in new stack
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Goto (macro-hangupcall,s,3)
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/SIPTRUNK-0000002b”, “0?Set(CDR(recordingfile)=)”) in new stack
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/SIPTRUNK-0000002b”, “”) in new stack
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/SIPTRUNK-0000002b’ in macro ‘hangupcall’
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/SIPTRUNK-0000002b’
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] res_musiconhold.c: – Stopped music on hold on SIP/66-0000002c
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/SIPTRUNK-0000002b’ in macro ‘dial’
[2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: == Spawn extension (from-did-direct, 66, 22) exited non-zero on ‘SIP/SIPTRUNK-0000002b’

Any ideas or hints how can I resolve this issue?

You have an incorectly configured find me follow me that is either sending someone to a MOH destination or is hanging up (either from a bad number or a misconfiguration in find me follow me)

So basically your calls are getting routed inbound and then going to some extension that has Find Me Follow Me Misconfigured. End users can change this from ARI and other places so it doesn’t matter if you think you didn’t change anything, something got changed.

Extension 66 seems to have the misconfigured find me follow me, so i would just delete the find me follow me on that extension and start fresh.