Call files don't moved in outgoing_done

Hello,

I configure asterisk for outgoing calls. Everything works, but I need to save the statuses of calls. Calls are initiated via call files.

astspooldir => /var/spool/asterisk

/etc/asterisk/extensions.conf

[from-zabbix]
exten => s,1,Answer
exten => s,n,Wait(1)
exten => s,n,MP3Player(${file})
exten => s,n,Wait(1)
exten => s,n,Hangup

/var/spool/asterisk/tmp/test.call

Channel: SIP/7921*******@pbx
Extension: s
Priority: 1
Context: from-zabbix
Archive: Yes
SetVar: file=/var/lib/asterisk/sounds/forme.mp3
WaitTime: 10

From docs:
To specify what to do with the call file at the end of processing:
Archive: <yes|no> - If “no” the call file is deleted. If set to “yes” the call file is moved to the “outgoing_done” subdirectory of the Asterisk spool directory. The default is to delete the call file.

Actions:

  1. I created a call file as “asterisk” user.
  2. I moved it to /var/spool/asterisk/outgoing/.
  3. I got a call.
  4. The directory /var/spool/asterisk/outgoing_done/ was created, but its empty.

Logs:

[Sep 21 18:32:59] VERBOSE[769] pbx_spool.c:     -- Attempting call on SIP/7921*@pbx for s@from-zabbix:1 (Retry 1)
[Sep 21 18:32:59] VERBOSE[769][C-00000010] netsock2.c:   == Using SIP RTP CoS mark 5
[Sep 21 18:33:07] VERBOSE[769][C-00000010] pbx.c:     -- Executing [s@from-zabbix:1] Answer("SIP/pbx-00000010", "") in new stack
[Sep 21 18:33:07] VERBOSE[769][C-00000010] pbx.c:     -- Executing [s@from-zabbix:2] Wait("SIP/pbx-00000010", "1") in new stack
[Sep 21 18:33:08] VERBOSE[769][C-00000010] pbx.c:     -- Executing [s@from-zabbix:3] MP3Player("SIP/pbx-00000010", "/var/lib/asterisk/sounds/forme.mp3") in new stack
[Sep 21 18:33:16] NOTICE[769][C-00000010] app_mp3.c: Poll timed out/errored out with 0
[Sep 21 18:33:16] NOTICE[769] pbx_spool.c: Call completed to SIP/7921*@pbx

Permissions:
/etc/asterisk/*

-rw-r--r-- 1 asterisk asterisk
# ls -la /var/spool/asterisk/(the outgoing_done directory itself is created with such permissons)
total 40
drwxr-x--- 10 asterisk asterisk 4096 Sep 21 18:21 .
drwxr-xr-x  6 root     root     4096 Sep  6 16:07 ..
drwxr-x---  2 asterisk asterisk 4096 Dec 24  2013 dictate
drwxr-x---  2 asterisk asterisk 4096 Dec 24  2013 meetme
drwxr-x---  2 asterisk asterisk 4096 Dec 24  2013 monitor
drwxr-x---  2 asterisk asterisk 4096 Sep 21 19:55 outgoing
drwxr-xr-x  2 asterisk asterisk 4096 Sep 21 18:21 outgoing_done
drwxr-x---  2 asterisk asterisk 4096 Dec 24  2013 system
drwxr-x---  2 asterisk asterisk 4096 Sep 21 19:54 tmp
drwxr-x---  2 asterisk asterisk 4096 Dec 24  2013 voicemail
# ls -la /var/spool/asterisk/tmp/
total 12
drwxr-x---  2 asterisk asterisk 4096 Sep 21 19:54 .
drwxr-x--- 10 asterisk asterisk 4096 Sep 21 18:21 ..
-rw-r--r--  1 asterisk asterisk  182 Sep 21 19:48 test.call