[asterisk-bugs] [Asterisk 0018685]: [REGRESSION]: Files based voicemail message re-sequencing still not working as expected.
Asterisk Bug Tracker
noreply at bugs.digium.com
Tue Feb 8 10:00:16 CST 2011
A NOTE has been added to this issue.
======================================================================
https://issues.asterisk.org/view.php?id=18685
======================================================================
Reported By: aragon
Assigned To:
======================================================================
Project: Asterisk
Issue ID: 18685
Category: Applications/app_voicemail
Reproducibility: always
Severity: minor
Priority: normal
Status: feedback
Asterisk Version: SVN
JIRA:
Regression: No
Reviewboard Link:
SVN Branch (only for SVN checkouts, not tarball releases): N/A
SVN Revision (number only!): 303676
Request Review:
======================================================================
Date Submitted: 2011-01-26 11:48 CST
Last Modified: 2011-02-08 10:00 CST
======================================================================
Summary: [REGRESSION]: Files based voicemail message
re-sequencing still not working as expected.
Description:
Files based voicemail message re-sequencing still not working as expected.
Cannot listed to new messages by using the following steps to reproduce.
check new messages and then press 9 to save the message and choose 0 to
save it to new messages (which puts it in INBOX again). Changes the file
names from 0000 to 0001 assuming there is only one message in the folder.
This problem still occurs after upgrading to revision 303676
======================================================================
----------------------------------------------------------------------
(0131669) aragon (reporter) - 2011-02-08 10:00
https://issues.asterisk.org/view.php?id=18685#c131669
----------------------------------------------------------------------
A simple test to reproduce the problem and show results in mailbox
directory.
[2011-02-08 10:55:51] -- Executing [*9806010 at default-super:1]
Dial("SIP/6010-00000000", "Local/6010 at default-local-voicemail") in new
stack
[2011-02-08 10:55:51] -- Called 6010 at default-local-voicemail
[2011-02-08 10:55:51] -- Executing [6010 at default-local-voicemail:1]
Set("Local/6010 at default-local-voicemail-5cbd,2",
"VMOPERATOR=Local/s at default-aa-operator-6010") in new stack
[2011-02-08 10:55:51] -- Executing [6010 at default-local-voicemail:2]
VoiceMail("Local/6010 at default-local-voicemail-5cbd,2", "6010 at default|b") in
new stack
[2011-02-08 10:55:51] -- Local/6010 at default-local-voicemail-5cbd,1
answered SIP/6010-00000000
[2011-02-08 10:55:51] -- <Local/6010 at default-local-voicemail-5cbd,2>
Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en')
[2011-02-08 10:55:53] -- <Local/6010 at default-local-voicemail-5cbd,2>
Playing 'beep' (language 'en')
[2011-02-08 10:55:54] -- Recording the message
[2011-02-08 10:55:54] -- x=0, open writing:
/var/spool/asterisk/voicemail/default/6010/tmp/h2lIPU format: wav49,
0xb5f14130
[2011-02-08 10:56:01] == Spawn extension (default-super, *9806010, 1)
exited non-zero on 'SIP/6010-00000000'
[2011-02-08 10:56:01] -- User hung up
[2011-02-08 10:56:01] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-08
10:56:01] Found
[2011-02-08 10:56:01] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-08
10:56:01] Found
[2011-02-08 10:56:01] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-08
10:56:01] Found
[2011-02-08 10:56:01] == Spawn extension (default-local-voicemail, 6010,
2) exited non-zero on 'Local/6010 at default-local-voicemail-5cbd,2'
[2011-02-08 10:56:03] == Parsing '/etc/asterisk/manager.conf':
[2011-02-08 10:56:03] Found
[2011-02-08 10:56:05] -- Executing [*9806010 at default-super:1]
Dial("SIP/6010-00000001", "Local/6010 at default-local-voicemail") in new
stack
[2011-02-08 10:56:05] -- Called 6010 at default-local-voicemail
[2011-02-08 10:56:05] -- Executing [6010 at default-local-voicemail:1]
Set("Local/6010 at default-local-voicemail-481c,2",
"VMOPERATOR=Local/s at default-aa-operator-6010") in new stack
[2011-02-08 10:56:05] -- Executing [6010 at default-local-voicemail:2]
VoiceMail("Local/6010 at default-local-voicemail-481c,2", "6010 at default|b") in
new stack
[2011-02-08 10:56:05] -- Local/6010 at default-local-voicemail-481c,1
answered SIP/6010-00000001
[2011-02-08 10:56:05] -- <Local/6010 at default-local-voicemail-481c,2>
Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en')
[2011-02-08 10:56:07] -- <Local/6010 at default-local-voicemail-481c,2>
Playing 'beep' (language 'en')
[2011-02-08 10:56:07] -- Recording the message
[2011-02-08 10:56:07] -- x=0, open writing:
/var/spool/asterisk/voicemail/default/6010/tmp/uZApad format: wav49,
0xb5f171c0
[2011-02-08 10:56:14] -- User hung up
[2011-02-08 10:56:14] == Spawn extension (default-super, *9806010, 1)
exited non-zero on 'SIP/6010-00000001'
[2011-02-08 10:56:14] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-08
10:56:14] Found
[2011-02-08 10:56:14] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-08
10:56:14] Found
[2011-02-08 10:56:14] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-08
10:56:14] Found
[2011-02-08 10:56:14] == Spawn extension (default-local-voicemail, 6010,
2) exited non-zero on 'Local/6010 at default-local-voicemail-481c,2'
[2011-02-08 10:56:18] -- Executing [*9806010 at default-super:1]
Dial("SIP/6010-00000002", "Local/6010 at default-local-voicemail") in new
stack
[2011-02-08 10:56:18] -- Called 6010 at default-local-voicemail
[2011-02-08 10:56:18] -- Executing [6010 at default-local-voicemail:1]
Set("Local/6010 at default-local-voicemail-9ea3,2",
"VMOPERATOR=Local/s at default-aa-operator-6010") in new stack
[2011-02-08 10:56:18] -- Executing [6010 at default-local-voicemail:2]
VoiceMail("Local/6010 at default-local-voicemail-9ea3,2", "6010 at default|b") in
new stack
[2011-02-08 10:56:18] -- Local/6010 at default-local-voicemail-9ea3,1
answered SIP/6010-00000002
[2011-02-08 10:56:18] -- <Local/6010 at default-local-voicemail-9ea3,2>
Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en')
[2011-02-08 10:56:19] -- <Local/6010 at default-local-voicemail-9ea3,2>
Playing 'beep' (language 'en')
[2011-02-08 10:56:20] -- Recording the message
[2011-02-08 10:56:20] -- x=0, open writing:
/var/spool/asterisk/voicemail/default/6010/tmp/qQPOLn format: wav49,
0x9ce97b8
[2011-02-08 10:56:28] -- User hung up
[2011-02-08 10:56:28] == Spawn extension (default-super, *9806010, 1)
exited non-zero on 'SIP/6010-00000002'
[2011-02-08 10:56:28] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-08
10:56:28] Found
[2011-02-08 10:56:28] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-08
10:56:28] Found
[2011-02-08 10:56:28] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-08
10:56:28] Found
[2011-02-08 10:56:28] == Spawn extension (default-local-voicemail, 6010,
2) exited non-zero on 'Local/6010 at default-local-voicemail-9ea3,2'
[2011-02-08 10:56:32] -- Executing [*981 at default-super:1]
Goto("SIP/6010-00000003", "default-app-voicemail-main|s|1") in new stack
[2011-02-08 10:56:32] -- Goto (default-app-voicemail-main,s,1)
[2011-02-08 10:56:32] -- Executing [s at default-app-voicemail-main:1]
NoOp("SIP/6010-00000003", "Voicemail Main") in new stack
[2011-02-08 10:56:32] -- Executing [s at default-app-voicemail-main:2]
Answer("SIP/6010-00000003", "") in new stack
[2011-02-08 10:56:32] -- Executing [s at default-app-voicemail-main:3]
AGI("SIP/6010-00000003", "agi://127.0.0.1:4573/extension") in new stack
[2011-02-08 10:56:32] agi://127.0.0.1:4573/extension: Variable
'PHONE_EXTEN_HD' result is not defined!!!
[2011-02-08 10:56:32] agi://127.0.0.1:4573/extension: Variable
'CHECK_HOTDESK' result is not defined!!!
[2011-02-08 10:56:32] -- AGI Script agi://127.0.0.1:4573/extension
completed, returning 0
[2011-02-08 10:56:32] -- Executing [s at default-app-voicemail-main:4]
Wait("SIP/6010-00000003", "1") in new stack
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:5]
Set("SIP/6010-00000003", "GROUP(OUTGOING)=6010") in new stack
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:6]
Set("SIP/6010-00000003", "TIMEOUT(absolute)=1200") in new stack
[2011-02-08 10:56:33] -- Channel will hangup at 2011-02-08 16:16:33
UTC.
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:7]
Set("SIP/6010-00000003", "TIMEOUT(digit)=5") in new stack
[2011-02-08 10:56:33] -- Digit timeout set to 5
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:8]
Set("SIP/6010-00000003", "TIMEOUT(response)=10") in new stack
[2011-02-08 10:56:33] -- Response timeout set to 10
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:9]
Set("SIP/6010-00000003", "DBKEY=/Voicemail/default/6010") in new stack
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:10]
GotoIf("SIP/6010-00000003", "0?11:14") in new stack
[2011-02-08 10:56:33] -- Goto (default-app-voicemail-main,s,14)
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:14]
Set("SIP/6010-00000003", "SCOPSERV_DBDEL(/Voicemail/default/6010)=") in new
stack
[2011-02-08 10:56:33] -- Executing [s at default-app-voicemail-main:15]
VoiceMailMain("SIP/6010-00000003", "6010 at default") in new stack
[2011-02-08 10:56:33] -- <SIP/6010-00000003> Playing 'vm-password'
(language 'en')
[2011-02-08 10:56:37] NOTICE[13756]: app_voicemail.c:6114 open_mailbox:
Resequencing Mailbox: /var/spool/asterisk/voicemail/default/6010/INBOX,
expected 100 but found 3 message(s) in box with max threshold of 100.
[2011-02-08 10:56:37] -- <SIP/6010-00000003> Playing 'vm-youhave'
(language 'en')
[2011-02-08 10:56:38] -- <SIP/6010-00000003> Playing 'digits/3'
(language 'en')
[2011-02-08 10:56:38] -- <SIP/6010-00000003> Playing 'vm-INBOX'
(language 'en')
[2011-02-08 10:56:39] -- <SIP/6010-00000003> Playing 'vm-messages'
(language 'en')
[2011-02-08 10:56:40] -- <SIP/6010-00000003> Playing 'vm-onefor'
(language 'en')
[2011-02-08 10:56:41] -- <SIP/6010-00000003> Playing 'vm-INBOX'
(language 'en')
[2011-02-08 10:56:42] -- <SIP/6010-00000003> Playing 'vm-messages'
(language 'en')
[2011-02-08 10:56:43] -- <SIP/6010-00000003> Playing 'vm-opts'
(language 'en')
[2011-02-08 10:56:43] -- <SIP/6010-00000003> Playing 'vm-first'
(language 'en')
[2011-02-08 10:56:44] -- <SIP/6010-00000003> Playing 'vm-message'
(language 'en')
[2011-02-08 10:56:45] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-08
10:56:45] Found
[2011-02-08 10:56:45] -- <SIP/6010-00000003> Playing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000' (language 'en')
[2011-02-08 10:56:47] -- <SIP/6010-00000003> Playing 'vm-deleted'
(language 'en')
[2011-02-08 10:56:48] -- <SIP/6010-00000003> Playing 'vm-advopts'
(language 'en')
[2011-02-08 10:56:50] -- <SIP/6010-00000003> Playing 'vm-repeat'
(language 'en')
[2011-02-08 10:56:50] -- <SIP/6010-00000003> Playing 'vm-message'
(language 'en')
[2011-02-08 10:56:51] -- <SIP/6010-00000003> Playing 'digits/2'
(language 'en')
[2011-02-08 10:56:52] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-08
10:56:52] Found
[2011-02-08 10:56:52] -- <SIP/6010-00000003> Playing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001' (language 'en')
[2011-02-08 10:56:55] -- <SIP/6010-00000003> Playing 'vm-deleted'
(language 'en')
[2011-02-08 10:56:56] -- <SIP/6010-00000003> Playing 'vm-prev'
(language 'en')
[2011-02-08 10:56:59] -- <SIP/6010-00000003> Playing 'vm-advopts'
(language 'en')
[2011-02-08 10:56:59] -- <SIP/6010-00000003> Playing 'vm-last'
(language 'en')
[2011-02-08 10:56:59] -- <SIP/6010-00000003> Playing 'vm-message'
(language 'en')
[2011-02-08 10:57:00] == Parsing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-08
10:57:00] Found
[2011-02-08 10:57:00] -- <SIP/6010-00000003> Playing
'/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002' (language 'en')
[2011-02-08 10:57:03] == Parsing '/etc/asterisk/manager.conf':
[2011-02-08 10:57:03] Found
[2011-02-08 10:57:04] -- <SIP/6010-00000003> Playing 'vm-savefolder'
(language 'en')
[2011-02-08 10:57:05] -- <SIP/6010-00000003> Playing 'vm-message'
(language 'en')
[2011-02-08 10:57:06] -- <SIP/6010-00000003> Playing 'digits/3'
(language 'en')
[2011-02-08 10:57:07] -- <SIP/6010-00000003> Playing 'vm-savedto'
(language 'en')
[2011-02-08 10:57:08] -- <SIP/6010-00000003> Playing 'vm-INBOX'
(language 'en')
[2011-02-08 10:57:08] -- <SIP/6010-00000003> Playing 'vm-messages'
(language 'en')
[2011-02-08 10:57:09] -- <SIP/6010-00000003> Playing 'vm-prev'
(language 'en')
[2011-02-08 10:57:11] == Spawn extension (default-app-voicemail-main, s,
15) exited non-zero on 'SIP/6010-00000003'
[2011-02-08 10:57:11] -- Executing [h at default-app-voicemail-main:1]
ResetCDR("SIP/6010-00000003", "w") in new stack
[2011-02-08 10:57:11] -- Executing [h at default-app-voicemail-main:2]
NoCDR("SIP/6010-00000003", "") in new stack
[2011-02-08 10:57:11] -- Executing [h at default-app-voicemail-main:3]
DeadAGI("SIP/6010-00000003",
"agi://127.0.0.1:4573/cdr?arg1=1297180592.18&arg2=dst&arg3=*981") in new
stack
[2011-02-08 10:57:11] -- AGI Script
agi://127.0.0.1:4573/cdr?arg1=1297180592.18&arg2=dst&arg3=*981 completed,
returning 0
[2011-02-08 10:57:16] -- Executing [*981 at default-super:1]
Goto("SIP/6010-00000004", "default-app-voicemail-main|s|1") in new stack
[2011-02-08 10:57:16] -- Goto (default-app-voicemail-main,s,1)
[2011-02-08 10:57:16] -- Executing [s at default-app-voicemail-main:1]
NoOp("SIP/6010-00000004", "Voicemail Main") in new stack
[2011-02-08 10:57:16] -- Executing [s at default-app-voicemail-main:2]
Answer("SIP/6010-00000004", "") in new stack
[2011-02-08 10:57:16] -- Executing [s at default-app-voicemail-main:3]
AGI("SIP/6010-00000004", "agi://127.0.0.1:4573/extension") in new stack
[2011-02-08 10:57:16] agi://127.0.0.1:4573/extension: Variable
'PHONE_EXTEN_HD' result is not defined!!!
[2011-02-08 10:57:16] agi://127.0.0.1:4573/extension: Variable
'CHECK_HOTDESK' result is not defined!!!
[2011-02-08 10:57:16] -- AGI Script agi://127.0.0.1:4573/extension
completed, returning 0
[2011-02-08 10:57:16] -- Executing [s at default-app-voicemail-main:4]
Wait("SIP/6010-00000004", "1") in new stack
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:5]
Set("SIP/6010-00000004", "GROUP(OUTGOING)=6010") in new stack
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:6]
Set("SIP/6010-00000004", "TIMEOUT(absolute)=1200") in new stack
[2011-02-08 10:57:17] -- Channel will hangup at 2011-02-08 16:17:17
UTC.
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:7]
Set("SIP/6010-00000004", "TIMEOUT(digit)=5") in new stack
[2011-02-08 10:57:17] -- Digit timeout set to 5
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:8]
Set("SIP/6010-00000004", "TIMEOUT(response)=10") in new stack
[2011-02-08 10:57:17] -- Response timeout set to 10
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:9]
Set("SIP/6010-00000004", "DBKEY=/Voicemail/default/6010") in new stack
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:10]
GotoIf("SIP/6010-00000004", "0?11:14") in new stack
[2011-02-08 10:57:17] -- Goto (default-app-voicemail-main,s,14)
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:14]
Set("SIP/6010-00000004", "SCOPSERV_DBDEL(/Voicemail/default/6010)=") in new
stack
[2011-02-08 10:57:17] -- Executing [s at default-app-voicemail-main:15]
VoiceMailMain("SIP/6010-00000004", "6010 at default") in new stack
[2011-02-08 10:57:17] -- <SIP/6010-00000004> Playing 'vm-password'
(language 'en')
[2011-02-08 10:57:21] -- <SIP/6010-00000004> Playing 'vm-youhave'
(language 'en')
[2011-02-08 10:57:22] -- <SIP/6010-00000004> Playing 'digits/1'
(language 'en')
[2011-02-08 10:57:22] -- <SIP/6010-00000004> Playing 'vm-INBOX'
(language 'en')
[2011-02-08 10:57:23] -- <SIP/6010-00000004> Playing 'vm-message'
(language 'en')
[2011-02-08 10:57:24] -- <SIP/6010-00000004> Playing 'vm-onefor'
(language 'en')
[2011-02-08 10:57:25] -- <SIP/6010-00000004> Playing 'vm-INBOX'
(language 'en')
[2011-02-08 10:57:25] -- <SIP/6010-00000004> Playing 'vm-first'
(language 'en')
[2011-02-08 10:57:26] -- <SIP/6010-00000004> Playing 'vm-message'
(language 'en')
[2011-02-08 10:57:27] WARNING[14335]: app_voicemail.c:6026 play_message:
No message attribute file?!!
(/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt)
[2011-02-08 10:57:27] -- <SIP/6010-00000004> Playing 'vm-advopts'
(language 'en')
[2011-02-08 10:57:29] -- <SIP/6010-00000004> Playing 'vm-repeat'
(language 'en')
[2011-02-08 10:57:31] == Spawn extension (default-app-voicemail-main, s,
15) exited non-zero on 'SIP/6010-00000004'
[2011-02-08 10:57:31] -- Executing [h at default-app-voicemail-main:1]
ResetCDR("SIP/6010-00000004", "w") in new stack
[2011-02-08 10:57:31] -- Executing [h at default-app-voicemail-main:2]
NoCDR("SIP/6010-00000004", "") in new stack
[2011-02-08 10:57:31] -- Executing [h at default-app-voicemail-main:3]
DeadAGI("SIP/6010-00000004",
"agi://127.0.0.1:4573/cdr?arg1=1297180636.19&arg2=dst&arg3=*981") in new
stack
[2011-02-08 10:57:31] -- AGI Script
agi://127.0.0.1:4573/cdr?arg1=1297180636.19&arg2=dst&arg3=*981 completed,
returning 0
lab*CLI>
Disconnected from Asterisk server
[Feb 8 10:57:32] Executing last minute cleanups
Asterisk ending (0).
[root at lab ~]# cd /var/spool/asterisk/voicemail/default/6010
[root at lab 6010]# cd INBOX
[root at lab INBOX]# dir
msg0003.txt msg0003.WAV
Issue History
Date Modified Username Field Change
======================================================================
2011-02-08 10:00 aragon Note Added: 0131669
======================================================================
More information about the asterisk-bugs
mailing list