Can't transfer calls

Hi guys,

I have a major problem here.
When we transfer a call to another extension only one party can here the other. In detail:

  • Ext.Call to “A”
  • “A” answers the call
  • “A” calls “B” and puts the Ext.Call on hold
  • “B” answers and is ready to get the Ext.Call
  • “A” transfers the call (snom phone hangup)
    !! - Ext.Call can now hear “B” but “B” cannot hear the Ext.Call !!

Please, can someone help me with this problem?

Here is the complete log of one call-transfer situation:

[2014-02-24 11:04:39] VERBOSE[27793][C-00000000] sig_pri.c: – Accepting call from ‘XXXXXXX’ to ‘XXXXXXX’ on channel 0/1, span 1
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “__FROM_DID=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Gosub(“DAHDI/i1/XXXXXXX-1”, “app-blacklist-check,s,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?blacklisted”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “CALLED_BLACKLIST=1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] Gosub(“DAHDI/i1/XXXXXXX-1”, “cidlookup,cidlookup_3,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “CURLOPT(httptimeout)=7”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “CALLERID(name)=XXXXXXX XXXXXXX (XXXXXXX, XXXXXXX)”) in new s$
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] Set(“DAHDI/i1/XXXXXXX-1”, “CDR(did)=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0 ?Set(CALLERID(name)=XXXXXXX)”) in new stack
[2014-02-24 11:04:39] WARNING[27857][C-00000000] func_callerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:6] Set(“DAHDI/i1/XXXXXXX-1”, “__CALLINGPRES_SV=allowed”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] Set(“DAHDI/i1/XXXXXXX-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:8] Goto(“DAHDI/i1/XXXXXXX-1”, “from-did-direct,21,1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (from-did-direct,21,1)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “__RINGTIMER=20”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Macro(“DAHDI/i1/XXXXXXX-1”, “exten-vm,novm,21,0,0,0”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Macro(“DAHDI/i1/XXXXXXX-1”, “user-callerid,”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “TOUCH_MONITOR=1393236279.0”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “AMPUSER=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?report”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] ExecIf(“DAHDI/i1/XXXXXXX-1”, “1?Set(REALCALLERIDNUM=XXXXXXX)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] Set(“DAHDI/i1/XXXXXXX-1”, “AMPUSER=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:6] Set(“DAHDI/i1/XXXXXXX-1”, “AMPUSERCIDNAME=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?report”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-user-callerid,s,15)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:15] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?continue”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:16] Set(“DAHDI/i1/XXXXXXX-1”, “__TTL=64”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:17] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?continue”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-user-callerid,s,28)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:28] Set(“DAHDI/i1/XXXXXXX-1”, “CALLERID(number)=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:29] Set(“DAHDI/i1/XXXXXXX-1”, “CALLERID(name)=XXXXXXXX (XXXXXXX)”)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:30] Set(“DAHDI/i1/XXXXXXX-1”, “CDR(cnum)=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:31] Set(“DAHDI/i1/XXXXXXX-1”, “CDR(cnam)=XXXXXXX XXXXXXX (XXXXXXX, XXXXXXX)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:32] Set(“DAHDI/i1/XXXXXXX-1”, “CHANNEL(language)=de”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “RingGroupMethod=none”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] Set(“DAHDI/i1/XXXXXXX-1”, “__EXTTOCALL=21”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] Set(“DAHDI/i1/XXXXXXX-1”, “__PICKUPMARK=21”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] Set(“DAHDI/i1/XXXXXXX-1”, “RT=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:6] Gosub(“DAHDI/i1/XXXXXXX-1”, “sub-record-check,s,1(exten,21,)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “REC_POLICY_MODE_SAVE=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?check”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (sub-record-check,s,7)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] Set(“DAHDI/i1/XXXXXXX-1”, “__MON_FMT=wav”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:8] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?next”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (sub-record-check,s,11)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:11] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Return()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:12] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:13] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?exten,1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:14] Set(“DAHDI/i1/XXXXXXX-1”, “__REC_STATUS=INITIALIZED”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:15] Set(“DAHDI/i1/XXXXXXX-1”, “NOW=1393236279”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:16] Set(“DAHDI/i1/XXXXXXX-1”, “__DAY=24”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:17] Set(“DAHDI/i1/XXXXXXX-1”, “__MONTH=02”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:18] Set(“DAHDI/i1/XXXXXXX-1”, “__YEAR=2014”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:19] Set(“DAHDI/i1/XXXXXXX-1”, “__TIMESTR=20140224-110439”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:20] Set(“DAHDI/i1/XXXXXXX-1”, “__FROMEXTEN=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:21] Set(“DAHDI/i1/XXXXXXX-1”, "__CALLFILENAME=exten-21-XXXXXXX-20140224-110439-1393$
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:22] Goto(“DAHDI/i1/XXXXXXX-1”, “exten,1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (sub-record-check,exten,1)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?callee”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “__REC_POLICY_MODE=dontcare”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?caller”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (sub-record-check,exten,10)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:10] Set(“DAHDI/i1/XXXXXXX-1”, “__REC_POLICY_MODE=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:11] GosubIf(“DAHDI/i1/XXXXXXX-1”, “0?record,1(exten,21,XXXXXXX)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:12] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?macrodial”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-exten-vm,s,13)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:13] GosubIf(“DAHDI/i1/XXXXXXX-1”, “0?clrheader,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:14] Macro(“DAHDI/i1/XXXXXXX-1”, “dial-one,Ttr,21”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “DEXTEN=21”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “DIALSTATUS_CW=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] GosubIf(“DAHDI/i1/XXXXXXX-1”, “0?screen,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] GosubIf(“DAHDI/i1/XXXXXXX-1”, “0?cf,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?skip1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-dial-one,s,8)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:8] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?nodial”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:9] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?continue”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:10] Set(“DAHDI/i1/XXXXXXX-1”, “EXTHASCW=ENABLED”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:11] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?next1:cwinusebusy”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-dial-one,s,23)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:23] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?next3:continue”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-dial-one,s,24)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:24] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:25] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?nodial”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:26] GosubIf(“DAHDI/i1/XXXXXXX-1”, “1?dstring,1():dlocal,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “DSTRING=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “DEVICES=211”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Return()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Set(DEVICES=11)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] Set(“DAHDI/i1/XXXXXXX-1”, “LOOPCNT=1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:6] Set(“DAHDI/i1/XXXXXXX-1”, “ITER=1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] Set(“DAHDI/i1/XXXXXXX-1”, “THISDIAL=SIP/211”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:8] GosubIf(“DAHDI/i1/XXXXXXX-1”, “1?zap2dahdi,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Return()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i1/XXXXXXX-1”, “NEWDIAL=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] Set(“DAHDI/i1/XXXXXXX-1”, “LOOPCNT2=1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] Set(“DAHDI/i1/XXXXXXX-1”, “ITER2=1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:5] Set(“DAHDI/i1/XXXXXXX-1”, “THISPART2=SIP/211”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:6] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Set(THISPART2=DAHDI/211)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:7] Set(“DAHDI/i1/XXXXXXX-1”, “NEWDIAL=SIP/211&”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:8] Set(“DAHDI/i1/XXXXXXX-1”, “ITER2=2”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:9] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?begin2”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:10] Set(“DAHDI/i1/XXXXXXX-1”, “THISDIAL=SIP/211”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:11] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:9] Set(“DAHDI/i1/XXXXXXX-1”, “DSTRING=SIP/211&”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:10] Set(“DAHDI/i1/XXXXXXX-1”, “ITER=2”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:11] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?begin”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:12] Set(“DAHDI/i1/XXXXXXX-1”, “DSTRING=SIP/211”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:13] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:27] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?nodial”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:28] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?skiptrace”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:29] GosubIf(“DAHDI/i1/XXXXXXX-1”, “1?ctset,1():ctclear,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i1/XXXXXXX-1”, “DB(CALLTRACE/21)=XXXXXXX”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:2] Return(“DAHDI/i1/XXXXXXX-1”, “”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:30] Set(“DAHDI/i1/XXXXXXX-1”, “D_OPTIONS=Ttr”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:31] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:32] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?SIPAddHeader()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:33] ExecIf(“DAHDI/i1/XXXXXXX-1”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:34] GosubIf(“DAHDI/i1/XXXXXXX-1”, “0?qwait,1()”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:35] Set(“DAHDI/i1/XXXXXXX-1”, “__CWIGNORE=”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:36] Set(“DAHDI/i1/XXXXXXX-1”, “__KEEPCID=TRUE”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:37] GotoIf(“DAHDI/i1/XXXXXXX-1”, “0?usegoto,1”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:38] GotoIf(“DAHDI/i1/XXXXXXX-1”, “1?godial”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-dial-one,s,42)
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:42] Dial(“DAHDI/i1/XXXXXXX-1”, “SIP/211,Ttr”) in new stack
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-24 11:04:39] DEBUG[27857][C-00000000] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Lhuoy4tc2SQPGL0BkkOi1iJiTZppKUbs65MgYoFS
[2014-02-24 11:04:39] VERBOSE[27857][C-00000000] app_dial.c: – Called SIP/211
[2014-02-24 11:04:40] VERBOSE[27857][C-00000000] app_dial.c: – SIP/211-00000000 is ringing
[2014-02-24 11:04:40] VERBOSE[27857][C-00000000] app_dial.c: – SIP/211-00000000 is ringing
[2014-02-24 11:04:41] VERBOSE[27857][C-00000000] app_dial.c: – SIP/211-00000000 is ringing
[2014-02-24 11:04:42] DEBUG[27811][C-00000000] sip/sdp_crypto.c: Accepting crypto tag 1
[2014-02-24 11:04:42] DEBUG[27811][C-00000000] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Lhuoy4tc2SQPGL0BkkOi1iJiTZppKUbs65MgYoFS
[2014-02-24 11:04:42] VERBOSE[27857][C-00000000] app_dial.c: – SIP/211-00000000 answered DAHDI/i1/XXXXXXX-1
[2014-02-24 11:04:54] DEBUG[27811][C-00000000] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Lhuoy4tc2SQPGL0BkkOi1iJiTZppKUbs65MgYoFS
[2014-02-24 11:04:54] VERBOSE[27857][C-00000000] res_musiconhold.c: – Started music on hold, class ‘default’, on DAHDI/i1/XXXXXXX-1
[2014-02-24 11:04:55] VERBOSE[27811][C-00000001] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-24 11:04:55] VERBOSE[27811][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-24 11:04:55] DEBUG[27811][C-00000001] sip/sdp_crypto.c: Accepting crypto tag 1
[2014-02-24 11:04:55] DEBUG[27811][C-00000001] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:vKU5xcbj/t8vKGWqqobUV9VoZ14KxJBnqUrfdjO8
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “__RINGTIMER=20”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Macro(“SIP/211-00000001”, “exten-vm,13,13,0,0,0”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Macro(“SIP/211-00000001”, “user-callerid,”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “TOUCH_MONITOR=1393236295.2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “AMPUSER=211”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/211-00000001”, “0?report”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/211-00000001”, “1?Set(REALCALLERIDNUM=211)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “AMPUSER=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] Set(“SIP/211-00000001”, “AMPUSERCIDNAME=Eingang”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/211-00000001”, “0?report”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] Set(“SIP/211-00000001”, “AMPUSERCID=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] Set(“SIP/211-00000001”, “__DIAL_OPTIONS=Ttr”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “CALLERID(all)=“Eingang” <21>”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/211-00000001”, “0?limit”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/211-00000001”, “0?Set(GROUP(concurrency_limit)=21)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:13] ExecIf(“SIP/211-00000001”, “0?Set(CHANNEL(language)=)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:14] GosubIf(“SIP/211-00000001”, “7?sub-ccss,s,1(macro-exten-vm,13)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “CCSS_SETUP=TRUE”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/211-00000001”, "0?monitor_config,1(macro-exten-vm,13):monitor_default,1(macro-exten$
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/211-00000001”, “1?is_exten”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (sub-ccss,monitor_default,4)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] Return(“SIP/211-00000001”, “TRUE”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/211-00000001”, “7?agent_config,1():agent_default,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] Set(“SIP/211-00000001”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2014-02-24 11:04:55] WARNING[27859][C-00000001] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/211-00000001”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] ExecIf(“SIP/211-00000001”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] ExecIf(“SIP/211-00000001”, "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected]$
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] Set(“SIP/211-00000001”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
[2014-02-24 11:04:55] WARNING[27859][C-00000001] ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “DB(AMPUSER/21/ccss/last_number)=13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/211-00000001”, “0?continue”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:16] Set(“SIP/211-00000001”, “__TTL=64”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:17] GotoIf(“SIP/211-00000001”, “1?continue”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-user-callerid,s,28)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:28] Set(“SIP/211-00000001”, “CALLERID(number)=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:29] Set(“SIP/211-00000001”, “CALLERID(name)=Eingang”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:30] Set(“SIP/211-00000001”, “CDR(cnum)=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:31] Set(“SIP/211-00000001”, “CDR(cnam)=Eingang”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:32] Set(“SIP/211-00000001”, “CHANNEL(language)=de”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “RingGroupMethod=none”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] Set(“SIP/211-00000001”, “__EXTTOCALL=13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “__PICKUPMARK=13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “RT=20”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] Gosub(“SIP/211-00000001”, “sub-record-check,s,1(exten,13,)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “REC_POLICY_MODE_SAVE=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/211-00000001”, “1?check”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (sub-record-check,s,7)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “__MON_FMT=wav”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/211-00000001”, “1?next”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (sub-record-check,s,11)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:12] ExecIf(“SIP/211-00000001”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/211-00000001”, “0?exten,1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:14] Set(“SIP/211-00000001”, “__REC_STATUS=INITIALIZED”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:15] Set(“SIP/211-00000001”, “NOW=1393236295”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:16] Set(“SIP/211-00000001”, “__DAY=24”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:17] Set(“SIP/211-00000001”, “__MONTH=02”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:18] Set(“SIP/211-00000001”, “__YEAR=2014”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:19] Set(“SIP/211-00000001”, “__TIMESTR=20140224-110455”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:20] Set(“SIP/211-00000001”, “__FROMEXTEN=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:21] Set(“SIP/211-00000001”, “__CALLFILENAME=exten-13-21-20140224-110455-1393236295.2”) in n$
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:22] Goto(“SIP/211-00000001”, “exten,1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (sub-record-check,exten,1)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/211-00000001”, “0?callee”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “__REC_POLICY_MODE=dontcare”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/211-00000001”, “1?caller”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (sub-record-check,exten,10)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “__REC_POLICY_MODE=dontcare”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GosubIf(“SIP/211-00000001”, “0?record,1(exten,13,21)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:12] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/211-00000001”, “1?macrodial”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-exten-vm,s,13)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/211-00000001”, “0?clrheader,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:14] Macro(“SIP/211-00000001”, “dial-one,20,Ttr,13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “DEXTEN=13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “DIALSTATUS_CW=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/211-00000001”, “0?screen,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/211-00000001”, “0?cf,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/211-00000001”, “1?skip1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-dial-one,s,8)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/211-00000001”, “0?nodial”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/211-00000001”, “0?continue”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “EXTHASCW=ENABLED”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/211-00000001”, “0?next1:cwinusebusy”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-dial-one,s,23)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/211-00000001”, “1?next3:continue”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-dial-one,s,24)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/211-00000001”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/211-00000001”, “0?nodial”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/211-00000001”, “1?dstring,1():dlocal,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “DSTRING=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “DEVICES=132&134&131”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/211-00000001”, “0?Set(DEVICES=32&134&131)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “LOOPCNT=3”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] Set(“SIP/211-00000001”, “ITER=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “THISDIAL=SIP/132”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/211-00000001”, “1?zap2dahdi,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “NEWDIAL=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] Set(“SIP/211-00000001”, “LOOPCNT2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “ITER2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “THISPART2=SIP/132”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/211-00000001”, “0?Set(THISPART2=DAHDI/132)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “NEWDIAL=SIP/132&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] Set(“SIP/211-00000001”, “ITER2=2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/211-00000001”, “0?begin2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “THISDIAL=SIP/132”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] Set(“SIP/211-00000001”, “DSTRING=SIP/132&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “ITER=2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/211-00000001”, “1?begin”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-dial-one,dstring,7)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “THISDIAL=SIP/134”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/211-00000001”, “1?zap2dahdi,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “NEWDIAL=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] Set(“SIP/211-00000001”, “LOOPCNT2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “ITER2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “THISPART2=SIP/134”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/211-00000001”, “0?Set(THISPART2=DAHDI/134)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “NEWDIAL=SIP/134&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] Set(“SIP/211-00000001”, “ITER2=2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/211-00000001”, “0?begin2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “THISDIAL=SIP/134”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]e:9] Set(“SIP/211-00000001”, “DSTRING=SIP/132&SIP/134&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “ITER=3”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/211-00000001”, “1?begin”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Goto (macro-dial-one,dstring,7)
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “THISDIAL=SIP/131”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] GosubIf(“SIP/211-00000001”, “1?zap2dahdi,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/211-00000001”, “0?Return()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Set(“SIP/211-00000001”, “NEWDIAL=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:3] Set(“SIP/211-00000001”, “LOOPCNT2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:4] Set(“SIP/211-00000001”, “ITER2=1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:5] Set(“SIP/211-00000001”, “THISPART2=SIP/131”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/211-00000001”, “0?Set(THISPART2=DAHDI/131)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:7] Set(“SIP/211-00000001”, “NEWDIAL=SIP/131&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:8] Set(“SIP/211-00000001”, “ITER2=2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/211-00000001”, “0?begin2”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “THISDIAL=SIP/131”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:9] Set(“SIP/211-00000001”, “DSTRING=SIP/132&SIP/134&SIP/131&”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:10] Set(“SIP/211-00000001”, “ITER=4”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/211-00000001”, “0?begin”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:12] Set(“SIP/211-00000001”, “DSTRING=SIP/132&SIP/134&SIP/131”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:13] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/211-00000001”, “0?nodial”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/211-00000001”, “0?skiptrace”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:29] GosubIf(“SIP/211-00000001”, “1?ctset,1():ctclear,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:1] Set(“SIP/211-00000001”, “DB(CALLTRACE/13)=21”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:2] Return(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:30] Set(“SIP/211-00000001”, “D_OPTIONS=Ttr”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/211-00000001”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/211-00000001”, “0?SIPAddHeader()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/211-00000001”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/211-00000001”, “0?qwait,1()”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:35] Set(“SIP/211-00000001”, “__CWIGNORE=”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:36] Set(“SIP/211-00000001”, “__KEEPCID=TRUE”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/211-00000001”, “0?usegoto,1”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/211-00000001”, “0?godial”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:39] Set(“SIP/211-00000001”, “CONNECTEDLINE(name,i)=XXXXXXX XXXXXXX”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:40] Set(“SIP/211-00000001”, “CONNECTEDLINE(num)=13”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:41] Set(“SIP/211-00000001”, “D_OPTIONS=TtrI”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] pbx.c: – Executing [[email protected]:42] Dial(“SIP/211-00000001”, “SIP/132&SIP/134&SIP/131,20,TtrI”) in new stack
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Called SIP/132
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Called SIP/134
[2014-02-24 11:04:55] DEBUG[27859][C-00000001] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:jiTLc4Cb0PS6FEXTHq62cUA6fACYtqkD3gCVuUzE
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Called SIP/131
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Connected line update to SIP/211-00000001 prevented.
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Connected line update to SIP/211-00000001 prevented.
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – Connected line update to SIP/211-00000001 prevented.
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – SIP/134-00000003 is ringing
[2014-02-24 11:04:55] VERBOSE[27859][C-00000001] app_dial.c: – SIP/131-00000004 is ringing
[2014-02-24 11:04:56] VERBOSE[27859][C-00000001] app_dial.c: – SIP/131-00000004 is ringing
[2014-02-24 11:04:56] DEBUG[27811][C-00000001] sip/sdp_crypto.c: Accepting crypto tag 1
[2014-02-24 11:04:56] DEBUG[27811][C-00000001] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:jiTLc4Cb0PS6FEXTHq62cUA6fACYtqkD3gCVuUzE
[2014-02-24 11:04:56] VERBOSE[27859][C-00000001] app_dial.c: – Connected line update to SIP/211-00000001 prevented.
[2014-02-24 11:04:56] VERBOSE[27859][C-00000001] app_dial.c: – SIP/131-00000004 answered SIP/211-00000001
[2014-02-24 11:04:56] DEBUG[27859][C-00000001] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:vKU5xcbj/t8vKGWqqobUV9VoZ14KxJBnqUrfdjO8
[2014-02-24 11:04:57] NOTICE[27795] chan_dahdi.c: PRI got event: HDLC Abort (6) on D-channel of span 3
[2014-02-24 11:04:58] DEBUG[27811][C-00000001] sip/sdp_crypto.c: Crypto line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:vKU5xcbj/t8vKGWqqobUV9VoZ14KxJBnqUrfdjO8
[2014-02-24 11:04:58] VERBOSE[27859][C-00000001] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/131-00000004
[2014-02-24 11:04:59] VERBOSE[27811][C-00000000] res_musiconhold.c: – Stopped music on hold on DAHDI/i1/XXXXXXX-1
[2014-02-24 11:04:59] VERBOSE[27811][C-00000000] res_musiconhold.c: – Stopped music on hold on SIP/131-00000004
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] Macro(“SIP/211-00000001”, “hangupcall,”) in new stack
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/211-00000001”, “1?theend”) in new stack
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: – Goto (macro-hangupcall,s,3)
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/211-00000001”, “0?Set(CDR(recordingfile)=)”) in new stack
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/211-00000001”, “”) in new stack
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/211-00000001’ in macro ‘hangupcall’
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on ‘SIP/211-00000001’
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘SIP/211-00000001’ in macro ‘dial-one’
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘SIP/211-00000001’ in macro ‘exten-vm’
[2014-02-24 11:04:59] VERBOSE[27857][C-00000000] pbx.c: == Spawn extension (from-did-direct, 21, 2) exited non-zero on ‘SIP/211-00000001’
[2014-02-24 11:05:01] ERROR[27796] chan_dahdi.c: PRI Span: 4 Unable to receive TEI from network in state 2(Assign awaiting TEI)!
[2014-02-24 11:05:13] NOTICE[27795] chan_dahdi.c: PRI got event: HDLC Abort (6) on D-channel of span 3

Dialplan logs don’t help trace audio issues.

Are the phones in the same network?

Meanwhile I could eliminate the problem by deactivating encryption in the devices settings.
Anyway a few phones are in another subnet than the server:
We have two locations connected by VPN.
10.0.0.x and 10.0.1.x (subnetMask 255.255.255.0)

So the calls have to pass the routers of the two VPN-servers.