Call Recording Scrambled after transfer

I am having a problem with recording calls, once the call gets transferred.

When the call is initiated or received, the recording is fine…however, once the call gets transferred, the recording become “scrambled” / “garbled”…

During playback of the call, everything is fine, up until the transfer, then you start hearing the scrambled / garbled portion…

Any ideas what may be causing this?
What settings I need to look at?

Thank you in advance for any help…

Always helps to see what the Asterisk CLI, or full logs are displaying.when transfer occurs, and after,

[2016-08-15 14:13:30] VERBOSE[4656][C-00020431] app_mixmonitor.c: == Begin MixMonitor Recording SIP/103-00009caf
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “NEWDIAL=”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] Set(“SIP/103-00009caf”, “LOOPCNT2=1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] Set(“SIP/103-00009caf”, “ITER2=1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:5] Set(“SIP/103-00009caf”, “THISPART2=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:6] ExecIf(“SIP/103-00009caf”, “0?Set(THISPART2=DAHDI/101)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:7] Set(“SIP/103-00009caf”, “NEWDIAL=SIP/101&”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:8] Set(“SIP/103-00009caf”, “ITER2=2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/103-00009caf”, “0?begin2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:10] Set(“SIP/103-00009caf”, “THISDIAL=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:11] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:9] Set(“SIP/103-00009caf”, “DSTRING=SIP/101&”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:10] Set(“SIP/103-00009caf”, “ITER=2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/103-00009caf”, “0?begin”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:12] Set(“SIP/103-00009caf”, “DSTRING=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:13] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:28] GotoIf(“SIP/103-00009caf”, “0?skiptrace”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:29] GosubIf(“SIP/103-00009caf”, “1?ctset,1():ctclear,1()”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “DB(CALLTRACE/101)=103”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:30] Set(“SIP/103-00009caf”, “D_OPTIONS=Ttr”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:31] ExecIf(“SIP/103-00009caf”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:32] ExecIf(“SIP/103-00009caf”, “0?SIPAddHeader()”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:33] ExecIf(“SIP/103-00009caf”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:34] GosubIf(“SIP/103-00009caf”, “0?qwait,1()”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:35] Set(“SIP/103-00009caf”, “__CWIGNORE=”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:36] Set(“SIP/103-00009caf”, “__KEEPCID=TRUE”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:37] GotoIf(“SIP/103-00009caf”, “0?usegoto,1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:38] GotoIf(“SIP/103-00009caf”, “0?godial”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:39] Gosub(“SIP/103-00009caf”, “sub-presencestate-display,s,1(101)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Goto(“SIP/103-00009caf”, “state-available,1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Goto (sub-presencestate-display,state-available,1)
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “PRESENCESTATE_DISPLAY=(Available)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:40] Set(“SIP/103-00009caf”, “CONNECTEDLINE(name,i)=Dispatch(Available)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:41] Set(“SIP/103-00009caf”, “CONNECTEDLINE(num)=101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:42] Set(“SIP/103-00009caf”, “D_OPTIONS=TtrI”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:43] Dial(“SIP/103-00009caf”, “SIP/101,15,TtrI”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] netsock2.c: == Using SIP RTP TOS bits 184
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] netsock2.c: == Using SIP RTP CoS mark 5
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] app_dial.c: – Called SIP/101
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] app_dial.c: – Connected line update to SIP/103-00009caf prevented.
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] app_dial.c: – SIP/101-00009cb0 is ringing
[2016-08-15 14:13:35] VERBOSE[4655][C-00020431] app_dial.c: – Connected line update to SIP/103-00009caf prevented.
[2016-08-15 14:13:35] VERBOSE[4655][C-00020431] app_dial.c: – SIP/101-00009cb0 answered SIP/103-00009caf
[2016-08-15 14:13:47] VERBOSE[4655][C-00020431] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/103-00009caf
[2016-08-15 14:13:47] VERBOSE[4655][C-00020431] file.c: – <SIP/101-00009cb0> Playing ‘pbx-transfer.ulaw’ (language ‘en’)
[2016-08-15 14:13:47] WARNING[7690][C-00020431] format_wav.c: Not in mono 2
[2016-08-15 14:13:47] WARNING[7690][C-00020431] file.c: Unable to open format wav
[2016-08-15 14:13:47] WARNING[7690][C-00020431] res_musiconhold.c: Unable to open file ‘/var/lib/asterisk/moh//Kalimba’: No such file or directory
[2016-08-15 14:13:47] VERBOSE[7690][C-00020431] res_musiconhold.c: – Stopped music on hold on SIP/103-00009caf
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] features.c: – Blind transferring SIP/103-00009caf to ‘1452’ (context from-internal-xfer) priority 1
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] app_macro.c: == Channel ‘SIP/103-00009caf’ jumping out of macro ‘dial-one’
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] app_macro.c: == Channel ‘SIP/103-00009caf’ jumping out of macro ‘exten-vm’
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “__RINGTIMER=15”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Macro(“SIP/103-00009caf”, “exten-vm,1452,1452,1,1,1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Macro(“SIP/103-00009caf”, “user-callerid,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “TOUCH_MONITOR=1471284810.275006”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “AMPUSER=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/103-00009caf”, “21?report”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-user-callerid,s,14)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/103-00009caf”, “0?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:15] Set(“SIP/103-00009caf”, “__TTL=63”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:16] GotoIf(“SIP/103-00009caf”, “1?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-user-callerid,s,27)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:27] Set(“SIP/103-00009caf”, “CALLERID(number)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:28] Set(“SIP/103-00009caf”, “CALLERID(name)=Network Administrator”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:29] Set(“SIP/103-00009caf”, “CDR(cnum)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:30] Set(“SIP/103-00009caf”, “CDR(cnam)=Network Administrator”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:31] Set(“SIP/103-00009caf”, “CHANNEL(language)=en”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “RingGroupMethod=none”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] Set(“SIP/103-00009caf”, “__EXTTOCALL=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] Set(“SIP/103-00009caf”, “__PICKUPMARK=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:5] Set(“SIP/103-00009caf”, “RT=15”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:6] Gosub(“SIP/103-00009caf”, “sub-record-check,s,1(exten,1452,)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “REC_POLICY_MODE_SAVE=always”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/103-00009caf”, “0?check”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] ResetCDR(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] GotoIf(“SIP/103-00009caf”, “0?check”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:5] Set(“SIP/103-00009caf”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:6] MixMonitor(“SIP/103-00009caf”, “2016/08/15/exten-101-103-20160815-141330-1471284810.275006.gsm,a,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:7] Set(“SIP/103-00009caf”, “__MON_FMT=gsm”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/103-00009caf”, “0?next”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:9] Set(“SIP/103-00009caf”, “CDR(recordingfile)=exten-101-103-20160815-141330-1471284810.275006.gsm”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:10] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/103-00009caf”, “1?macrodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-exten-vm,s,13)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:13] GosubIf(“SIP/103-00009caf”, “0?clrheader,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:14] Macro(“SIP/103-00009caf”, “dial-one,15,Ttr,1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “DEXTEN=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “DIALSTATUS_CW=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] GosubIf(“SIP/103-00009caf”, “0?screen,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] GosubIf(“SIP/103-00009caf”, “0?cf,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/103-00009caf”, “1?skip1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-dial-one,s,8)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:9] GotoIf(“SIP/103-00009caf”, “0?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:10] Set(“SIP/103-00009caf”, “EXTHASCW=ENABLED”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/103-00009caf”, “0?next1:cwinusebusy”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-dial-one,s,23)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:23] GotoIf(“SIP/103-00009caf”, “1?next3:continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-dial-one,s,24)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:24] ExecIf(“SIP/103-00009caf”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:25] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:26] GosubIf(“SIP/103-00009caf”, “1?dstring,1():dlocal,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “DSTRING=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “DEVICES=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/103-00009caf”, “1?Return()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:27] GotoIf(“SIP/103-00009caf”, “1?nodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-dial-one,s,48)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:48] ExecIf(“SIP/103-00009caf”, “0?Set(DIALSTATUS=NOANSWER)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:49] NoOp(“SIP/103-00009caf”, “Returned from dial-one with nothing to call and DIALSTATUS: ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:50] MacroExit(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:15] Set(“SIP/103-00009caf”, “SV_DIALSTATUS=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:16] GosubIf(“SIP/103-00009caf”, “0?docfu,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4657][C-00020431] app_mixmonitor.c: == Begin MixMonitor Recording SIP/103-00009caf
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:17] GosubIf(“SIP/103-00009caf”, “0?docfb,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:18] Set(“SIP/103-00009caf”, “DIALSTATUS=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:19] ExecIf(“SIP/103-00009caf”, “0?MacroExit()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:20] GotoIf(“SIP/103-00009caf”, “0?s-ANSWER,1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:21] Macro(“SIP/103-00009caf”, “vm,1452,ANSWER,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Macro(“SIP/103-00009caf”, “user-callerid,SKIPTTL”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “TOUCH_MONITOR=1471284810.275006”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “AMPUSER=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] GotoIf(“SIP/103-00009caf”, “21?report”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-user-callerid,s,14)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:14] GotoIf(“SIP/103-00009caf”, “1?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-user-callerid,s,27)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:27] Set(“SIP/103-00009caf”, “CALLERID(number)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:28] Set(“SIP/103-00009caf”, “CALLERID(name)=Network Administrator”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:29] Set(“SIP/103-00009caf”, “CDR(cnum)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:30] Set(“SIP/103-00009caf”, “CDR(cnam)=Network Administrator”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:31] Set(“SIP/103-00009caf”, “CHANNEL(language)=en”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “VMGAIN=g(12)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] Macro(“SIP/103-00009caf”, “blkvm-check,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “GOSUB_RETVAL=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] ExecIf(“SIP/103-00009caf”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] MacroExit(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] GotoIf(“SIP/103-00009caf”, “1?vmx,1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-vm,vmx,1)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “MEXTEN=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] Set(“SIP/103-00009caf”, “MMODE=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:3] Set(“SIP/103-00009caf”, “RETVM=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:4] Set(“SIP/103-00009caf”, “MODE=unavail”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:5] GotoIf(“SIP/103-00009caf”, “1?chknomsg”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-vm,vmx,7)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/103-00009caf”, “0?s-ANSWER,1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:8] GotoIf(“SIP/103-00009caf”, “1?notdirect”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-vm,vmx,10)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:10] NoOp(“SIP/103-00009caf”, "Checking if ext 1452 is enabled: ") in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/103-00009caf”, “1?s-ANSWER,1”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-vm,s-ANSWER,1)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Macro(“SIP/103-00009caf”, “get-vmcontext,1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:1] Set(“SIP/103-00009caf”, “VMCONTEXT=default”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/103-00009caf”, “0?200:300”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Goto (macro-get-vmcontext,s,300)
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:300] NoOp(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [[email protected]:2] VoiceMail(“SIP/103-00009caf”, “[email protected],ug(12)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘vm-theperson.ulaw’ (language ‘en’)
[2016-08-15 14:13:54] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘digits/1.ulaw’ (language ‘en’)
[2016-08-15 14:13:54] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘digits/4.ulaw’ (language ‘en’)
[2016-08-15 14:13:55] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘digits/5.ulaw’ (language ‘en’)
[2016-08-15 14:13:56] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘digits/2.ulaw’ (language ‘en’)
[2016-08-15 14:13:56] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘vm-isunavail.ulaw’ (language ‘en’)
[2016-08-15 14:13:57] VERBOSE[4655][C-00020431] file.c: – <SIP/103-00009caf> Playing ‘vm-intro.ulaw’ (language ‘en’)
[2016-08-15 14:14:00] VERBOSE[4658] pbx_spool.c: – Attempting call on Local/[email protected] for application NoCDR() (Retry 1)
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [[email protected]:1] NoCDR(“Local/[email protected];2”, “”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “TCMAINT=RETURN”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?timeconditions,1,1()”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [[email protected]:4] System(“Local/[email protected];2”, “/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [[email protected]:5] Answer(“Local/[email protected];2”, “”) in new stack
[2016-08-15 14:14:00] NOTICE[4658] pbx_spool.c: Call completed to Local/[email protected]

These two excerpts struck me immediately. The fact that your system is having trouble with the wav file format would be an indicator of a problem that could result in some odd transcoding.

I don’t know, though. It might be a complete red herring and be perfectly normal.

To trouble shoot it, I’d probably start with all of the ‘wav’ file support stuff and make sure that your system can handle wav files. If it can and the problem persists, then it could be in the wav-gsm transcoding. To trouble shoot that, change the default recording format to something other than GSM (which might not be a bad idea anyway). Normally, I use “wav” as my default recording format, but you’re having trouble with ‘wav’ files, so that might not be an option for you.