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 [zap2dahdi@macro-dial-one:2] Set(“SIP/103-00009caf”, “NEWDIAL=”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/103-00009caf”, “LOOPCNT2=1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/103-00009caf”, “ITER2=1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/103-00009caf”, “THISPART2=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one: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 [zap2dahdi@macro-dial-one:7] Set(“SIP/103-00009caf”, “NEWDIAL=SIP/101&”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/103-00009caf”, “ITER2=2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/103-00009caf”, “0?begin2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/103-00009caf”, “THISDIAL=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/103-00009caf”, “DSTRING=SIP/101&”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/103-00009caf”, “ITER=2”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/103-00009caf”, “0?begin”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/103-00009caf”, “DSTRING=SIP/101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/103-00009caf”, “0?skiptrace”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [ctset@macro-dial-one: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 [ctset@macro-dial-one:2] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/103-00009caf”, “D_OPTIONS=Ttr”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one:32] ExecIf(“SIP/103-00009caf”, “0?SIPAddHeader()”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one:34] GosubIf(“SIP/103-00009caf”, “0?qwait,1()”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/103-00009caf”, “__CWIGNORE=”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/103-00009caf”, “__KEEPCID=TRUE”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:37] GotoIf(“SIP/103-00009caf”, “0?usegoto,1”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:38] GotoIf(“SIP/103-00009caf”, “0?godial”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@sub-presencestate-display: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 [state-available@sub-presencestate-display:1] Set(“SIP/103-00009caf”, “PRESENCESTATE_DISPLAY=(Available)”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [state-available@sub-presencestate-display:2] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one:41] Set(“SIP/103-00009caf”, “CONNECTEDLINE(num)=101”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:42] Set(“SIP/103-00009caf”, “D_OPTIONS=TtrI”) in new stack
[2016-08-15 14:13:30] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [1452@from-internal-xfer:1] Set(“SIP/103-00009caf”, “__RINGTIMER=15”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [1452@from-internal-xfer: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 [s@macro-exten-vm:1] Macro(“SIP/103-00009caf”, “user-callerid,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:2] Set(“SIP/103-00009caf”, “AMPUSER=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:14] GotoIf(“SIP/103-00009caf”, “0?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid:15] Set(“SIP/103-00009caf”, “__TTL=63”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:27] Set(“SIP/103-00009caf”, “CALLERID(number)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:29] Set(“SIP/103-00009caf”, “CDR(cnum)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:31] Set(“SIP/103-00009caf”, “CHANNEL(language)=en”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/103-00009caf”, “RingGroupMethod=none”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/103-00009caf”, “__EXTTOCALL=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/103-00009caf”, “__PICKUPMARK=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/103-00009caf”, “RT=15”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm: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 [s@sub-record-check: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 [s@sub-record-check:2] GotoIf(“SIP/103-00009caf”, “0?check”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@sub-record-check:3] ResetCDR(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“SIP/103-00009caf”, “0?check”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check:7] Set(“SIP/103-00009caf”, “__MON_FMT=gsm”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/103-00009caf”, “0?next”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@sub-record-check: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 [s@sub-record-check:10] Return(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm: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 [s@macro-exten-vm:13] GosubIf(“SIP/103-00009caf”, “0?clrheader,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm: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 [s@macro-dial-one:1] Set(“SIP/103-00009caf”, “DEXTEN=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/103-00009caf”, “DIALSTATUS_CW=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/103-00009caf”, “0?screen,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/103-00009caf”, “0?cf,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one:8] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/103-00009caf”, “0?continue”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/103-00009caf”, “EXTHASCW=ENABLED”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one:25] GotoIf(“SIP/103-00009caf”, “0?nodial”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [dstring@macro-dial-one:1] Set(“SIP/103-00009caf”, “DSTRING=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/103-00009caf”, “DEVICES=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/103-00009caf”, “1?Return()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one: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 [s@macro-dial-one:50] MacroExit(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:15] Set(“SIP/103-00009caf”, “SV_DIALSTATUS=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm: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 [s@macro-exten-vm:17] GosubIf(“SIP/103-00009caf”, “0?docfb,1()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:18] Set(“SIP/103-00009caf”, “DIALSTATUS=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf(“SIP/103-00009caf”, “0?MacroExit()”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-exten-vm: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 [s@macro-exten-vm:21] Macro(“SIP/103-00009caf”, “vm,1452,ANSWER,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-vm:1] Macro(“SIP/103-00009caf”, “user-callerid,SKIPTTL”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:2] Set(“SIP/103-00009caf”, “AMPUSER=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid:27] Set(“SIP/103-00009caf”, “CALLERID(number)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:29] Set(“SIP/103-00009caf”, “CDR(cnum)=103”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-user-callerid: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 [s@macro-user-callerid:31] Set(“SIP/103-00009caf”, “CHANNEL(language)=en”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-vm:2] Set(“SIP/103-00009caf”, “VMGAIN=g(12)”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-vm:3] Macro(“SIP/103-00009caf”, “blkvm-check,”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-blkvm-check:1] Set(“SIP/103-00009caf”, “GOSUB_RETVAL=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-blkvm-check: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 [s@macro-blkvm-check:3] MacroExit(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-vm: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 [vmx@macro-vm:1] Set(“SIP/103-00009caf”, “MEXTEN=1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [vmx@macro-vm:2] Set(“SIP/103-00009caf”, “MMODE=ANSWER”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [vmx@macro-vm:3] Set(“SIP/103-00009caf”, “RETVM=”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [vmx@macro-vm:4] Set(“SIP/103-00009caf”, “MODE=unavail”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [vmx@macro-vm: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 [vmx@macro-vm: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 [vmx@macro-vm: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 [vmx@macro-vm: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 [vmx@macro-vm: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 [s-ANSWER@macro-vm:1] Macro(“SIP/103-00009caf”, “get-vmcontext,1452”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-get-vmcontext:1] Set(“SIP/103-00009caf”, “VMCONTEXT=default”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s@macro-get-vmcontext: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 [s@macro-get-vmcontext:300] NoOp(“SIP/103-00009caf”, “”) in new stack
[2016-08-15 14:13:52] VERBOSE[4655][C-00020431] pbx.c: – Executing [s-ANSWER@macro-vm:2] VoiceMail(“SIP/103-00009caf”, “1452@default,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/s@tc-maint for application NoCDR() (Retry 1)
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [s@tc-maint:1] NoCDR(“Local/s@tc-maint-0001cac4;2”, “”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [s@tc-maint:2] Set(“Local/s@tc-maint-0001cac4;2”, “TCMAINT=RETURN”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [s@tc-maint:3] GosubIf(“Local/s@tc-maint-0001cac4;2”, “0?timeconditions,1,1()”) in new stack
[2016-08-15 14:14:00] VERBOSE[4659][C-00020432] pbx.c: – Executing [s@tc-maint:4] System(“Local/s@tc-maint-0001cac4;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 [s@tc-maint:5] Answer(“Local/s@tc-maint-0001cac4;2”, “”) in new stack
[2016-08-15 14:14:00] NOTICE[4658] pbx_spool.c: Call completed to Local/s@tc-maint
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.