Hangup and MOH on incoming calls

filister · July 23, 2014, 12:27pm

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?

filister · July 23, 2014, 12:30pm

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

filister · July 23, 2014, 12:30pm

Dial(“Local/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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 [s@macro-auto-blkvm:1] Set(“SIP/66-0000002c”, “__MACRO_RESULT=”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:2] Set(“SIP/66-0000002c”, “CFIGNORE=”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:3] Set(“SIP/66-0000002c”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm: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 [s@macro-auto-blkvm: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 [s@macro-auto-blkvm:6] Macro(“SIP/66-0000002c”, “blkvm-clr,”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr: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 [s@macro-blkvm-clr:2] Set(“SIP/66-0000002c”, “GOSUB_RETVAL=”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/66-0000002c”, “”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm: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 [s@macro-auto-blkvm: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/FMPR-66@from-internal-00000015;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 [h@from-internal: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 [s@macro-auto-blkvm:1] Set(“Local/FMPR-66@from-internal-00000015;1”, “__MACRO_RESULT=”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:2] Set(“Local/FMPR-66@from-internal-00000015;1”, “CFIGNORE=”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:3] Set(“Local/FMPR-66@from-internal-00000015;1”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:4] Set(“Local/FMPR-66@from-internal-00000015;1”, “FORWARD_CONTEXT=from-internal”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:5] Set(“Local/FMPR-66@from-internal-00000015;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 [s@macro-auto-blkvm:6] Macro(“Local/FMPR-66@from-internal-00000015;1”, “blkvm-clr,”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“Local/FMPR-66@from-internal-00000015;1”, “SHARED(BLKVM,SIP/SIPTRUNK-0000002b)=”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“Local/FMPR-66@from-internal-00000015;1”, “GOSUB_RETVAL=”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“Local/FMPR-66@from-internal-00000015;1”, “”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:7] ExecIf(“Local/FMPR-66@from-internal-00000015;1”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=FMPR-66@from-internal)”) in new stack [2014-07-23 12:30:42] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:8] ExecIf(“Local/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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 [h@macro-dial-one:1] Macro(“Local/FMPR-66@from-internal-00000015;2”, “hangupcall,”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“Local/FMPR-66@from-internal-00000015;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 [s@macro-hangupcall:3] ExecIf(“Local/FMPR-66@from-internal-00000015;2”, “0?Set(CDR(recordingfile)=)”) in new stack [2014-07-23 12:30:42] VERBOSE[2476][C-00000012] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“Local/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;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/FMPR-66@from-internal-00000015;2’ [2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [h@macro-dial:1] Macro(“SIP/SIPTRUNK-0000002b”, “hangupcall”) in new stack [2014-07-23 12:30:55] VERBOSE[2474][C-00000012] pbx.c: – Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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’

filister · July 23, 2014, 3:49pm

Any ideas or hints how can I resolve this issue?

tm1000 · July 23, 2014, 8:30pm

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.