Call dropped from IVR to Ringgroup

Dear All,

I do have some trouble, I have setup some ringgroups and an IVR. The call comes in went through the IVR and Rings all Phones in the Ringgroup. This is working as expected. The the issue happends. After the Phone is lift up, the call will be disconnected. Attached here a CallLog.

[2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:11] ExecIf("SIP/98765-00001c3b", "0?Return()") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/98765-00001c3b", "0?Set(__REC_POLICY_MODE=dontcare)") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/98765-00001c3b", "0?rg,1") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:14] Set("SIP/98765-00001c3b", "__REC_STATUS=INITIALIZED") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:15] Set("SIP/98765-00001c3b", "NOW=1392809852") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:16] Set("SIP/98765-00001c3b", "__DAY=19") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:17] Set("SIP/98765-00001c3b", "__MONTH=02") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:18] Set("SIP/98765-00001c3b", "__YEAR=2014") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:19] Set("SIP/98765-00001c3b", "__TIMESTR=20140219-123732") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:20] Set("SIP/98765-00001c3b", "__FROMEXTEN=004176316XXXX") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:21] Set("SIP/98765-00001c3b", "__CALLFILENAME=rg-505-004176316XXXX-20140219-123732-1392809826.8618") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:22] Goto("SIP/98765-00001c3b", "rg,1") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Goto (sub-record-check,rg,1) [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] GosubIf("SIP/98765-00001c3b", "0?record,1(rg,never,004176316XXXX)") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:2] Return("SIP/98765-00001c3b", "") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:10] Set("SIP/98765-00001c3b", "RingGroupMethod=ringall") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:11] Macro("SIP/98765-00001c3b", "dial,100,Ttr,300-301-200-201") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/98765-00001c3b", "1?dial") in new stack [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Goto (macro-dial,s,3) [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:3] AGI("SIP/98765-00001c3b", "dialparties.agi") in new stack [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Starting New Dialparties.agi [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Caller ID name is '004176316XXXX' number is '004176316XXXX' [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Methodology of ring is 'ringall' [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Added extension 300 to extension map [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Added extension 301 to extension map [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Added extension 200 to extension map [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Added extension 201 to extension map [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 300 cf is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 301 cf is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 200 cf is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 201 cf is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 300 do not disturb is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 301 do not disturb is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 200 do not disturb is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Extension 201 do not disturb is disabled [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Extension 300 has ExtensionState: 0 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 300 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/300 - Caller ID is not defined [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Extension 301 has ExtensionState: 0 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 301 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/301 - Caller ID is not defined [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: dialparties.agi: Extension 200 has ExtensionState: 0 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 200 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/200 - Caller ID is not defined [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/201 - Caller ID is not defined [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- dialparties.agi: Filtered ARG3: 300-301-200-201 [2014-02-19 12:37:32] VERBOSE[32570] res_agi.c: -- AGI Script dialparties.agi completed, returning 0 [2014-02-19 12:37:32] VERBOSE[32570] pbx.c: -- Executing [[email protected]:7] Dial("SIP/98765-00001c3b", "SIP/300&SIP/301&SIP/200&SIP/201,100,TtrM(auto-blkvm)") in new stack [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP TOS bits 184 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP CoS mark 5 [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- Called SIP/300 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP TOS bits 184 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP CoS mark 5 [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- Called SIP/301 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP TOS bits 184 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP CoS mark 5 [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- Called SIP/200 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP TOS bits 184 [2014-02-19 12:37:32] VERBOSE[32570] netsock2.c: == Using SIP RTP CoS mark 5 [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- Called SIP/201 [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/300-00001c3c connected line has changed. Saving it until answer for SIP/98765-00001c3b [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/301-00001c3d connected line has changed. Saving it until answer for SIP/98765-00001c3b [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/200-00001c3e connected line has changed. Saving it until answer for SIP/98765-00001c3b [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/201-00001c3f connected line has changed. Saving it until answer for SIP/98765-00001c3b [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/201-00001c3f is ringing [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/300-00001c3c is ringing [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/200-00001c3e is ringing [2014-02-19 12:37:32] VERBOSE[32570] app_dial.c: -- SIP/301-00001c3d is ringing [2014-02-19 12:38:31] VERBOSE[32570] app_dial.c: -- SIP/300-00001c3c connected line has changed. Saving it until answer for SIP/98765-00001c3b [2014-02-19 12:38:31] VERBOSE[32570] app_dial.c: -- SIP/300-00001c3c answered SIP/98765-00001c3b [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] Set("SIP/300-00001c3c", "__MACRO_RESULT=") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:2] Set("SIP/300-00001c3c", "CFIGNORE=") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]kvm:3] Set("SIP/300-00001c3c", "MASTER_CHANNEL(CFIGNORE)=") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:4] Set("SIP/300-00001c3c", "FORWARD_CONTEXT=from-internal") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:5] Set("SIP/300-00001c3c", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:6] Macro("SIP/300-00001c3c", "blkvm-clr,") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] Set("SIP/300-00001c3c", "SHARED(BLKVM,SIP/98765-00001c3b)=") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] Macro("SIP/98765-00001c3b", "hangupcall") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/98765-00001c3b", "1?theend") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Goto (macro-hangupcall,s,3) [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/98765-00001c3b", "0?Set(CDR(recordingfile)=)") in new stack [2014-02-19 12:38:31] VERBOSE[32570] pbx.c: -- Executing [[email protected]:4] Hangup("SIP/98765-00001c3b", "") in new stack [2014-02-19 12:38:31] VERBOSE[32570] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/98765-00001c3b' in macro 'hangupcall' [2014-02-19 12:38:31] VERBOSE[32570] features.c: == Spawn extension (macro-dial, h, 1) exited non-zero on 'SIP/98765-00001c3b' [2014-02-19 12:38:32] VERBOSE[32570] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/98765-00001c3b' in macro 'dial' [2014-02-19 12:38:32] VERBOSE[32570] pbx.c: == Spawn extension (ext-group, 505, 11) exited non-zero on 'SIP/98765-00001c3b'

can someone help?