в cdr не сохраняется имя файла записи разговора

установлен freepbx 12 и asterisk 13.6. Asterisk и freepbx собирались из исходников. Собралось все без ошибок.

Везде все пути стандартные. Активирована запись звонков для нужных направлений. Запись звонков производится, есть файлы в /var/spool/asterisk/monitor/ - читаются и воспроизводятся корректно. (формат wav)

но в mysql не сохраняется информации об этом файле - поле recordingfile пусто для всех вызовов. в логах __CALLFILENAME и recordingfile везде есть, и соответствует файлу записи.

перерыл уже кучу материала из гугла, не могу понять куда копать и причины пустого имени файла в БД.

[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@macro-exten-vm:6] Gosub("PJSIP/123-0000000d", "sub-record-check,s,1(exten,1020,dontcare)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/123-0000000d", "0?initialized") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/123-0000000d", "__REC_STATUS=INITIALIZED") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/123-0000000d", "NOW=1448720472") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/123-0000000d", "__DAY=28") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/123-0000000d", "__MONTH=11") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/123-0000000d", "__YEAR=2015") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/123-0000000d", "__TIMESTR=20151128-172112") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/123-0000000d", "__FROMEXTEN=123") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/123-0000000d", "__MON_FMT=wav") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/123-0000000d", "Recordings initialized") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/123-0000000d", "0?Set(ARG3=dontcare)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/123-0000000d", "REC_POLICY_MODE_SAVE=") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/123-0000000d", "0?Set(REC_STATUS=NO)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/123-0000000d", "5?checkaction") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Goto (sub-record-check,s,17)
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/123-0000000d", "1?sub-record-check,exten,1") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Goto (sub-record-check,exten,1)
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/123-0000000d", "Exten Recording Check between 123 and 1020") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/123-0000000d", "CALLTYPE=internal") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/123-0000000d", "0?Set(CALLTYPE=)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/123-0000000d", "CALLEE=dontcare") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/123-0000000d", "0?Set(CALLEE=dontcare)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/123-0000000d", "0?callee") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:7] GotoIf("PJSIP/123-0000000d", "1?caller") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Goto (sub-record-check,exten,13)
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:13] Set("PJSIP/123-0000000d", "RECMODE=yes") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:14] ExecIf("PJSIP/123-0000000d", "0?Set(RECMODE=dontcare)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:15] ExecIf("PJSIP/123-0000000d", "0?Set(RECMODE=dontcare)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [exten@sub-record-check:16] Gosub("PJSIP/123-0000000d", "recordcheck,1(yes,internal,1020)") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/123-0000000d", "Starting recording check against yes") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/123-0000000d", "yes") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Goto (sub-record-check,recordcheck,9)
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("PJSIP/123-0000000d", "0?Return()") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:10] Set("PJSIP/123-0000000d", "__REC_POLICY_MODE=YES") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("PJSIP/123-0000000d", "startrec") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Goto (sub-record-check,recordcheck,16)
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/123-0000000d", "Starting recording: internal, 1020") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:17] Set("PJSIP/123-0000000d", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-11-28 17:21:12] NOTICE[19986][C-00000009] func_audiohookinherit.c: AUDIOHOOK_INHERIT is deprecated and now does nothing.
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:18] Set("PJSIP/123-0000000d", "__CALLFILENAME=internal-1020-123-20151128-172112-1448720472.90") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor("PJSIP/123-0000000d", "2015/11/28/internal-1020-123-20151128-172112-1448720472.90.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-11-28 17:21:12] VERBOSE[19987][C-00000009] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/123-0000000d
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:20] Set("PJSIP/123-0000000d", "__MIXMON_ID=0x7f9d7400a640") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:21] Set("PJSIP/123-0000000d", "__RECORD_ID=PJSIP/123-0000000d") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:22] Set("PJSIP/123-0000000d", "__REC_STATUS=RECORDING") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:23] Set("PJSIP/123-0000000d", "CDR(recordingfile)=internal-1020-123-20151128-172112-1448720472.90.wav") in new stack
[2015-11-28 17:21:12] VERBOSE[19986][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:24] Return("PJSIP/123-0000000d", "") in new stack