Hi,
we run Asterisk 1.6.2 on Centos 5.5 (i686) (with freepbx 2.8).
When transfering incoming calls (unattended transfer) to another extension, the caller very often stops hearing music on hold, and seems to be disconnected from the active call; here is what happens in my own words:
call comes in (on linksys voip adapter) and rings on ext. 11
11 answers, puts call on hold using TRANSFER key, and dials extension
if there is no answer, 11 tries to take the caller off hold, but finds that that calls is (or at least SEEMS to have been) disconnected
when 11 tries to take the caller off hold, the music on hold stops, and the caller gets the impression that he has been disconnected.
Several minutes later, the music on hold starts playing again in the system - even though both parties have already hung up. (in the example below, the call gets terminated nearly 7 minutes after the music on hold stops
this seems to be a known bug in older versions, but everywhere I read about this, this seems to have been fixed in the latest versions. We have this in 1.6.2, though (installed as part of AsteriskNow), and last night I upgraded to 1.8.13.0 (from the downloaded RPM) , but this keeps happening still.
Question: should i have installed asterisk from scratch, rather than upgrading? I downloaded the RPM, configure/make/install and that was it. maybe i should have stopped the asterisk service while doing make/install - or shoudl that not have mattered?
I am wondering if there is a patch for this for 1.8.13?
[code][Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:1] Set(“SIP/1-pstn-00002a96”, “__FROM_DID=933569798”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:2] ExecIf(“SIP/1-pstn-00002a96”, “0 ?Set(CALLERID(name)=913207491)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:3] Ringing(“SIP/1-pstn-00002a96”, “”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:4] Set(“SIP/1-pstn-00002a96”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:5] Set(“SIP/1-pstn-00002a96”, “CALLERPRES()=allowed_not_screened”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [933569798@from-trunk:6] Goto(“SIP/1-pstn-00002a96”, “from-did-direct,11,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (from-did-direct,11,1)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [11@from-did-direct:1] Set(“SIP/1-pstn-00002a96”, “__RINGTIMER=45”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [11@from-did-direct:2] Macro(“SIP/1-pstn-00002a96”, “exten-vm,11,11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/1-pstn-00002a96”, “user-callerid,”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/1-pstn-00002a96”, “AMPUSER=913207491”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/1-pstn-00002a96”, “0?report”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/1-pstn-00002a96”, “1?Set(REALCALLERIDNUM=913207491)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/1-pstn-00002a96”, “AMPUSER=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/1-pstn-00002a96”, “AMPUSERCIDNAME=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/1-pstn-00002a96”, “1?report”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-user-callerid,s,9)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/1-pstn-00002a96”, “0?continue”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/1-pstn-00002a96”, “__TTL=64”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:11] GotoIf(“SIP/1-pstn-00002a96”, “1?continue”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-user-callerid,s,18)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:18] Set(“SIP/1-pstn-00002a96”, “CALLERID(number)=913207491”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/1-pstn-00002a96”, “CALLERID(name)=llamada entrante”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-user-callerid:20] NoOp(“SIP/1-pstn-00002a96”, “Using CallerID “llamada entrante” <913207491>”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/1-pstn-00002a96”, “RingGroupMethod=none”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/1-pstn-00002a96”, “VMBOX=11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/1-pstn-00002a96”, “__EXTTOCALL=11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/1-pstn-00002a96”, “CFUEXT=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/1-pstn-00002a96”, “CFBEXT=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/1-pstn-00002a96”, “RT=45”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/1-pstn-00002a96”, “record-enable,11,IN”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/1-pstn-00002a96”, “1?check”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-record-enable,s,4)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/1-pstn-00002a96”, “0?MacroExit()”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/1-pstn-00002a96”, “0?Group:OUT”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-record-enable,s,15)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/1-pstn-00002a96”, “1?IN”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-record-enable,s,20)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/1-pstn-00002a96”, “1?MacroExit()”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/1-pstn-00002a96”, “dial-one,45,tr,11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/1-pstn-00002a96”, “DEXTEN=11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/1-pstn-00002a96”, “DIALSTATUS_CW=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/1-pstn-00002a96”, “0?screen,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/1-pstn-00002a96”, “0?cf,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/1-pstn-00002a96”, “1?skip1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-dial-one,s,8)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/1-pstn-00002a96”, “0?nodial”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/1-pstn-00002a96”, “0?continue”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/1-pstn-00002a96”, “EXTHASCW=ENABLED”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/1-pstn-00002a96”, “0?next1:cwinusebusy”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-dial-one,s,23)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:23] GotoIf(“SIP/1-pstn-00002a96”, “1?next3:continue”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-dial-one,s,24)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:24] ExecIf(“SIP/1-pstn-00002a96”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/1-pstn-00002a96”, “0?nodial”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/1-pstn-00002a96”, “1?dstring,1:dlocal,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/1-pstn-00002a96”, “DSTRING=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/1-pstn-00002a96”, “DEVICES=11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/1-pstn-00002a96”, “0?Return()”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/1-pstn-00002a96”, “0?Set(DEVICES=1)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/1-pstn-00002a96”, “LOOPCNT=1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/1-pstn-00002a96”, “ITER=1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/1-pstn-00002a96”, “THISDIAL=SIP/11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/1-pstn-00002a96”, “1?zap2dahdi,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/1-pstn-00002a96”, “0?Return()”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/1-pstn-00002a96”, “NEWDIAL=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/1-pstn-00002a96”, “LOOPCNT2=1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/1-pstn-00002a96”, “ITER2=1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/1-pstn-00002a96”, “THISPART2=SIP/11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/1-pstn-00002a96”, “0?Set(THISPART2=DAHDI/11)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/1-pstn-00002a96”, “NEWDIAL=SIP/11&”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/1-pstn-00002a96”, “ITER2=2”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/1-pstn-00002a96”, “0?begin2”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/1-pstn-00002a96”, “THISDIAL=SIP/11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/1-pstn-00002a96”, “”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/1-pstn-00002a96”, “DSTRING=SIP/11&”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/1-pstn-00002a96”, “ITER=2”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/1-pstn-00002a96”, “0?begin”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/1-pstn-00002a96”, “DSTRING=SIP/11”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/1-pstn-00002a96”, “”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/1-pstn-00002a96”, “0?nodial”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/1-pstn-00002a96”, “1?skiptrace”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Goto (macro-dial-one,s,30)
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/1-pstn-00002a96”, “D_OPTIONS=tr”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/1-pstn-00002a96”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/1-pstn-00002a96”, “0?SIPAddHeader()”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/1-pstn-00002a96”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/1-pstn-00002a96”, “0?qwait,1”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/1-pstn-00002a96”, “__CWIGNORE=”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/1-pstn-00002a96”, “__KEEPCID=TRUE”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] pbx.c: – Executing [s@macro-dial-one:37] Dial(“SIP/1-pstn-00002a96”, “SIP/11,45,tr”) in new stack
[Jun 5 17:15:32] VERBOSE[14149] netsock.c: == Using SIP RTP TOS bits 184
[Jun 5 17:15:32] VERBOSE[14149] netsock.c: == Using SIP RTP CoS mark 5
[Jun 5 17:15:32] VERBOSE[14149] app_dial.c: – Called 11
[Jun 5 17:15:33] VERBOSE[14149] app_dial.c: – SIP/11-00002a97 is ringing
[Jun 5 17:15:35] VERBOSE[14149] app_dial.c: – SIP/11-00002a97 answered SIP/1-pstn-00002a96
[Jun 5 17:15:46] VERBOSE[14149] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/1-pstn-00002a96
[Jun 5 17:15:51] VERBOSE[14441] netsock.c: == Using SIP RTP TOS bits 184
[Jun 5 17:15:51] VERBOSE[14441] netsock.c: == Using SIP RTP CoS mark 5
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [12@from-internal:1] Macro(“SIP/11-00002a98”, “exten-vm,12,12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/11-00002a98”, “user-callerid,”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/11-00002a98”, “AMPUSER=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/11-00002a98”, “0?report”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/11-00002a98”, “1?Set(REALCALLERIDNUM=11)”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/11-00002a98”, “AMPUSER=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/11-00002a98”, “AMPUSERCIDNAME=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/11-00002a98”, “0?report”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/11-00002a98”, “AMPUSERCID=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/11-00002a98”, “CALLERID(all)=“11” <11>”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/11-00002a98”, “0?continue”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/11-00002a98”, “__TTL=64”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:11] GotoIf(“SIP/11-00002a98”, “1?continue”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-user-callerid,s,18)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:18] Set(“SIP/11-00002a98”, “CALLERID(number)=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/11-00002a98”, “CALLERID(name)=11”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-user-callerid:20] NoOp(“SIP/11-00002a98”, “Using CallerID “11” <11>”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/11-00002a98”, “RingGroupMethod=none”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/11-00002a98”, “VMBOX=12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/11-00002a98”, “__EXTTOCALL=12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/11-00002a98”, “CFUEXT=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/11-00002a98”, “CFBEXT=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/11-00002a98”, “RT=15”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/11-00002a98”, “record-enable,12,IN”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/11-00002a98”, “1?check”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-record-enable,s,4)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/11-00002a98”, “0?MacroExit()”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/11-00002a98”, “0?Group:OUT”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-record-enable,s,15)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/11-00002a98”, “1?IN”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-record-enable,s,20)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/11-00002a98”, “1?MacroExit()”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/11-00002a98”, “dial-one,15,tr,12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/11-00002a98”, “DEXTEN=12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/11-00002a98”, “DIALSTATUS_CW=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/11-00002a98”, “0?screen,1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/11-00002a98”, “0?cf,1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/11-00002a98”, “1?skip1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-dial-one,s,8)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/11-00002a98”, “0?nodial”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/11-00002a98”, “0?continue”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/11-00002a98”, “EXTHASCW=ENABLED”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/11-00002a98”, “0?next1:cwinusebusy”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-dial-one,s,23)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:23] GotoIf(“SIP/11-00002a98”, “1?next3:continue”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-dial-one,s,24)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:24] ExecIf(“SIP/11-00002a98”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/11-00002a98”, “0?nodial”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/11-00002a98”, “1?dstring,1:dlocal,1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/11-00002a98”, “DSTRING=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/11-00002a98”, “DEVICES=12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/11-00002a98”, “0?Return()”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/11-00002a98”, “0?Set(DEVICES=2)”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/11-00002a98”, “LOOPCNT=1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/11-00002a98”, “ITER=1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/11-00002a98”, “THISDIAL=SIP/12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/11-00002a98”, “1?zap2dahdi,1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/11-00002a98”, “0?Return()”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/11-00002a98”, “NEWDIAL=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/11-00002a98”, “LOOPCNT2=1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/11-00002a98”, “ITER2=1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/11-00002a98”, “THISPART2=SIP/12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/11-00002a98”, “0?Set(THISPART2=DAHDI/12)”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/11-00002a98”, “NEWDIAL=SIP/12&”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/11-00002a98”, “ITER2=2”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/11-00002a98”, “0?begin2”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/11-00002a98”, “THISDIAL=SIP/12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/11-00002a98”, “”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/11-00002a98”, “DSTRING=SIP/12&”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/11-00002a98”, “ITER=2”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/11-00002a98”, “0?begin”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/11-00002a98”, “DSTRING=SIP/12”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/11-00002a98”, “”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/11-00002a98”, “0?nodial”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/11-00002a98”, “1?skiptrace”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Goto (macro-dial-one,s,30)
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/11-00002a98”, “D_OPTIONS=tr”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/11-00002a98”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/11-00002a98”, “0?SIPAddHeader()”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/11-00002a98”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/11-00002a98”, “0?qwait,1”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/11-00002a98”, “__CWIGNORE=”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/11-00002a98”, “__KEEPCID=TRUE”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] pbx.c: – Executing [s@macro-dial-one:37] Dial(“SIP/11-00002a98”, “SIP/12,15,tr”) in new stack
[Jun 5 17:15:51] VERBOSE[14150] netsock.c: == Using SIP RTP TOS bits 184
[Jun 5 17:15:51] VERBOSE[14150] netsock.c: == Using SIP RTP CoS mark 5
[Jun 5 17:15:51] VERBOSE[14150] app_dial.c: – Called 12
[Jun 5 17:15:52] VERBOSE[14150] app_dial.c: – SIP/12-00002a99 is ringing
[Jun 5 17:15:54] VERBOSE[14150] app_dial.c: – SIP/12-00002a99 answered SIP/11-00002a98
[Jun 5 17:16:08] VERBOSE[14441] res_musiconhold.c: – Stopped music on hold on SIP/1-pstn-00002a96
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Executing [h@macro-dial-one:1] Macro(“SIP/11-00002a98”, “hangupcall,”) in new stack
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/11-00002a98”, “1?skiprg”) in new stack
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/11-00002a98”, “1?skipblkvm”) in new stack
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Goto (macro-hangupcall,s,7)
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/11-00002a98”, “1?theend”) in new stack
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/11-00002a98”, “”) in new stack
[Jun 5 17:16:08] VERBOSE[14149] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/11-00002a98’ in macro ‘hangupcall’
[Jun 5 17:16:08] VERBOSE[14149] app_macro.c: == Spawn extension (macro-dial-one, s, 37) exited non-zero on ‘SIP/11-00002a98’ in macro ‘dial-one’
[Jun 5 17:16:08] VERBOSE[14149] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on ‘SIP/11-00002a98’ in macro ‘exten-vm’
[Jun 5 17:16:08] VERBOSE[14149] pbx.c: == Spawn extension (from-did-direct, 11, 2) exited non-zero on ‘SIP/11-00002a98’
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [h@macro-dial-one:1] Macro(“SIP/1-pstn-00002a96”, “hangupcall,”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/1-pstn-00002a96”, “1?skiprg”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/1-pstn-00002a96”, “1?skipblkvm”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,7)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/1-pstn-00002a96”, “1?theend”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/1-pstn-00002a96”, “”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/1-pstn-00002a96’ in macro ‘hangupcall’
[Jun 5 17:22:53] VERBOSE[14150] features.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on ‘SIP/1-pstn-00002a96’
[Jun 5 17:22:53] VERBOSE[14150] app_macro.c: == Spawn extension (macro-dial-one, s, 37) exited non-zero on ‘SIP/1-pstn-00002a96’ in macro ‘dial-one’
[Jun 5 17:22:53] VERBOSE[14150] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on ‘SIP/1-pstn-00002a96’ in macro ‘exten-vm’
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: == Spawn extension (from-internal, 12, 1) exited non-zero on ‘SIP/1-pstn-00002a96’
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [h@from-internal:1] Macro(“SIP/1-pstn-00002a96”, “hangupcall”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/1-pstn-00002a96”, “1?skiprg”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/1-pstn-00002a96”, “1?skipblkvm”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,7)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/1-pstn-00002a96”, “1?theend”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/1-pstn-00002a96”, “”) in new stack
[Jun 5 17:22:53] VERBOSE[14150] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/1-pstn-00002a96’ in macro ‘hangupcall’
[Jun 5 17:22:53] VERBOSE[14150] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1-pstn-00002a96’[/code]