Recording filename OK location NULL

[c20xh2]

Vicidial:
VERSION: 2.14-506c BUILD: 161222-1111
Asterisk:
Asterisk 11.25.1-vici

For the last 3 days my Vicidial is not recording any outbound call (didn't had the chance to test inbounds).

The thing is I can see all the call details in the recording_log(start_time, length, etc..), except the location. Tried ALLCALL and ALLFORCE in the campaign options.

Also I tried to change the permission on the monitor and monitorDONE to give ownership to asterisk (was previously owned by root).

Worth to mention that I have a trunk between an older asterisk box and this asterisk. The old box pass the calls to the SIP provider.

Any clue ?

Thank you

[williamconley]

Your installation method will be helpful here since it's unlikely to be a programming issue, but possible. Please post your installer (Vicibox?) with version (4.0.3? 5.0.2?).

Is this is a single server system?

Is your HD full?

What are the contents of the folders in /var/spool/asterisk/monitorDONE/?

Are you archiving your recordings?

Have you verified that your mysql tables are not crashed? (Note that a crashed table can stop a process, but if that process is not essential ... you may only notice that something is amiss when specific actions don't happen that would occur after the crashed table access fails, such as updating the filename for a recording)

Have a look at the Asterisk CLI during a test call to see if there is an error related to recordings (or any error for that matter). Not "full system operation" with 3000 lines of unrelated code. ONE test call of the type you expect to be recorded. See if the recording happens or if the code expecting to generate the recording tosses an error.

[c20xh2]

Sorry for the missing details,

This is a cluster install, (1 DB, 1 WEB, 1 Asterisk
The install is fresh, HD is under 10% use

Contents of monitorDONE/:

Code: Select all

FTP  FTP2  GPG  GSM  GSW  MP3  OGG  ORIG

Only have files in MP3:

Code: Select all

20170802-152121_5063500694-all.mp3  20170802-162601_5063500893-all.mp3
20170802-152205_5065485801-all.mp3  20170802-162659_5065457084-all.mp3
20170802-152539_5065484352-all.mp3  20170802-162711_5063500769-all.mp3

I'm not archiving the recordings
Mysql seems to be OK, did a reset on the cluster to be sure

This call should have been recorded:

Code: Select all

[Aug 18 00:46:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Aug 18 00:46:02]     -- Executing [4389291847@default:1] AGI("Local/4389291847@default-0000000c;2", "agi://127.0.0.1:4577/call_log") in new stack
[Aug 18 00:46:02]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=1000))
[Aug 18 00:46:02]     -- <Local/4389291847@default-0000000c;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Aug 18 00:46:02]     -- Executing [4389291847@default:2] Dial("Local/4389291847@default-0000000c;2", "SIP/Server_B/4389291847,,tTor") in new stack
[Aug 18 00:46:02]   == Using SIP RTP CoS mark 5
[Aug 18 00:46:02]     -- Called SIP/Server_B/4389291847
[Aug 18 00:46:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Aug 18 00:46:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Aug 18 00:46:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Aug 18 00:46:08]     -- SIP/Server_B-00000009 is making progress passing it to Local/4389291847@default-0000000c;2
[Aug 18 00:46:12]     -- SIP/Server_B-00000009 answered Local/4389291847@default-0000000c;2
[Aug 18 00:46:12]        > Channel Local/4389291847@default-0000000c;1 was answered
[Aug 18 00:46:12]     -- Executing [8368@default:1] Playback("Local/4389291847@default-0000000c;1", "sip-silence") in new stack
[Aug 18 00:46:12]     -- <Local/4389291847@default-0000000c;1> Playing 'sip-silence.gsm' (language 'en')
[Aug 18 00:46:12]     -- Executing [8368@default:2] AGI("Local/4389291847@default-0000000c;1", "agi://127.0.0.1:4577/call_log") in new stack
[Aug 18 00:46:12]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=1000))
[Aug 18 00:46:12]     -- <Local/4389291847@default-0000000c;1>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Aug 18 00:46:12]     -- Executing [8368@default:3] AGI("Local/4389291847@default-0000000c;1", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Aug 18 00:46:12]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Aug 18 00:46:12]        > 0x7f563401a180 -- Probation passed - setting RTP source address to 192.168.15.14:18714
[Aug 18 00:46:12]     -- Executing [h@default:1] AGI("Local/4389291847@default-0000000c;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----10-----0") in new stack
[Aug 18 00:46:13]   == Manager 'sendcron' logged off from 127.0.0.1
[Aug 18 00:46:13]     -- <Local/4389291847@default-0000000c;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----10-----0 completed, returning 0
[Aug 18 00:46:13]   == Spawn extension (default, 4389291847, 2) exited non-zero on 'Local/4389291847@default-0000000c;2'
[Aug 18 00:46:13]     -- <SIP/Server_B-00000009>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Aug 18 00:46:13]     -- Executing [8368@default:4] AGI("SIP/Server_B-00000009", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Aug 18 00:46:13]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Aug 18 00:46:13]     -- <SIP/Server_B-00000009>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Aug 18 00:46:13]     -- Executing [192*168*015*071*8600051@default:1] Goto("SIP/Server_B-00000009", "default,8600051,1") in new stack
[Aug 18 00:46:13]     -- Goto (default,8600051,1)
[Aug 18 00:46:13]     -- Executing [8600051@default:1] MeetMe("SIP/Server_B-00000009", "8600051,F") in new stack
[Aug 18 00:46:15]   == Manager 'sendcron' logged on from 127.0.0.1
[Aug 18 00:46:15]     -- Executing [58600051@default:1] MeetMe("Local/58600051@default-0000000d;2", "8600051,Fmq") in new stack
[Aug 18 00:46:15]        > Channel Local/58600051@default-0000000d;1 was answered
[Aug 18 00:46:15]     -- Executing [8369@default:1] Playback("Local/58600051@default-0000000d;1", "sip-silence") in new stack
[Aug 18 00:46:15]     -- <Local/58600051@default-0000000d;1> Playing 'sip-silence.gsm' (language 'en')
[Aug 18 00:46:15]     -- Executing [8369@default:2] AGI("Local/58600051@default-0000000d;1", "agi://127.0.0.1:4577/call_log") in new stack
[Aug 18 00:46:15]     -- <Local/58600051@default-0000000d;1>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Aug 18 00:46:15]     -- Executing [8369@default:3] AMD("Local/58600051@default-0000000d;1", "2000,2000,1000,5000,120,50,4,256") in new stack
[Aug 18 00:46:15]     -- AMD: Local/58600051@default-0000000d;1 (N/A) (N/A) (Fmt: slin)
[Aug 18 00:46:15]     -- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256] maximumWordLength [5000]
[Aug 18 00:46:15]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Changed state to STATE_IN_SILENCE
[Aug 18 00:46:15]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Word detected. iWordsCount:1
[Aug 18 00:46:15]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Detected Talk, previous silence duration: 100
[Aug 18 00:46:15]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Changed state to STATE_IN_SILENCE
[Aug 18 00:46:15]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Detected Talk, previous silence duration: 300
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Word detected. iWordsCount:2
[Aug 18 00:46:16]   == Manager 'sendcron' logged off from 127.0.0.1
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Detected Talk, previous silence duration: 40
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Changed state to STATE_IN_SILENCE
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Detected Talk, previous silence duration: 160
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Word detected. iWordsCount:3
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Changed state to STATE_IN_SILENCE
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Detected Talk, previous silence duration: 160
[Aug 18 00:46:16]     -- AMD: Channel [Local/58600051@default-0000000d;1]. Short Word Duration: 40
[Aug 18 00:46:17]     -- AMD: Channel [Local/58600051@default-0000000d;1]. HUMAN: silenceDuration:1000 afterGreetingSilence:1000
[Aug 18 00:46:17]     -- Executing [8369@default:4] AGI("Local/58600051@default-0000000d;1", "VD_amd.agi,8369") in new stack
[Aug 18 00:46:17]     -- Launched AGI Script /usr/share/asterisk/agi-bin/VD_amd.agi
[Aug 18 00:46:18]     -- <Local/58600051@default-0000000d;1>AGI Script VD_amd.agi completed, returning 0
[Aug 18 00:46:18]     -- Executing [8369@default:5] AGI("Local/58600051@default-0000000d;1", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Aug 18 00:46:18]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Aug 18 00:46:20]     -- <Local/58600051@default-0000000d;1>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Aug 18 00:46:20]     -- Executing [8369@default:6] AGI("Local/58600051@default-0000000d;1", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Aug 18 00:46:20]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Aug 18 00:46:21]     -- <Local/58600051@default-0000000d;1>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Aug 18 00:46:21]     -- Executing [8369@default:7] Hangup("Local/58600051@default-0000000d;1", "") in new stack
[Aug 18 00:46:21]   == Spawn extension (default, 8369, 7) exited non-zero on 'Local/58600051@default-0000000d;1'
[Aug 18 00:46:21]     -- Executing [h@default:1] AGI("Local/58600051@default-0000000d;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Aug 18 00:46:21]     -- <Local/58600051@default-0000000d;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Aug 18 00:46:21]   == Spawn extension (default, 58600051, 1) exited non-zero on 'Local/58600051@default-0000000d;2'
[Aug 18 00:46:21]     -- Executing [h@default:1] AGI("Local/58600051@default-0000000d;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Aug 18 00:46:21]     -- <Local/58600051@default-0000000d;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Aug 18 00:46:30]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/Server_B-00000009'
[Aug 18 00:46:30]     -- Executing [h@default:1] AGI("SIP/Server_B-00000009", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Aug 18 00:46:30]     -- <SIP/Server_B-00000009>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Aug 18 00:47:02]   == Manager 'sendcron' logged on from 127.0.0.1



Sorry for the delay, was out of town for a week

[williamconley]

Did call recording stop when you turned on AMD?

What settings do you have for the users or recording?