[2011-02-01 15:16:28] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-01 15:16:28] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-01 15:16:28] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-01 15:16:28] -- Executing [*9806010@default-super:1] Dial("SIP/6010-00000005", "Local/6010@default-local-voicemail") in new stack [2011-02-01 15:16:28] -- Called 6010@default-local-voicemail [2011-02-01 15:16:28] -- Executing [6010@default-local-voicemail:1] Set("Local/6010@default-local-voicemail-fc15,2", "VMOPERATOR=Local/s@default-aa-operator-6010") in new stack [2011-02-01 15:16:28] -- Executing [6010@default-local-voicemail:2] VoiceMail("Local/6010@default-local-voicemail-fc15,2", "6010@default|b") in new stack [2011-02-01 15:16:28] -- Local/6010@default-local-voicemail-fc15,1 answered SIP/6010-00000005 [2011-02-01 15:16:28] -- Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en') [2011-02-01 15:16:29] -- Playing 'beep' (language 'en') [2011-02-01 15:16:30] -- Recording the message [2011-02-01 15:16:30] -- x=0, open writing: /var/spool/asterisk/voicemail/default/6010/tmp/L4N1Nf format: wav49, 0x896ed18 [2011-02-01 15:16:35] == Spawn extension (default-super, *9806010, 1) exited non-zero on 'SIP/6010-00000005' [2011-02-01 15:16:35] -- User hung up [2011-02-01 15:16:35] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-01 15:16:35] Found [2011-02-01 15:16:35] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-01 15:16:35] Found [2011-02-01 15:16:35] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-01 15:16:35] Found [2011-02-01 15:16:36] == Spawn extension (default-local-voicemail, 6010, 2) exited non-zero on 'Local/6010@default-local-voicemail-fc15,2' [2011-02-01 15:16:36] Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2011-02-01 15:16:36] Extension Changed 6010[default-local] new state Idle for Notify User 6002 [2011-02-01 15:16:36] Extension Changed 6010[default-local] new state Idle for Notify User 6010 [2011-02-01 15:16:40] -- Executing [*9806010@default-super:1] Dial("SIP/6010-00000006", "Local/6010@default-local-voicemail") in new stack [2011-02-01 15:16:40] -- Called 6010@default-local-voicemail [2011-02-01 15:16:40] -- Executing [6010@default-local-voicemail:1] Set("Local/6010@default-local-voicemail-ab0b,2", "VMOPERATOR=Local/s@default-aa-operator-6010") in new stack [2011-02-01 15:16:40] -- Executing [6010@default-local-voicemail:2] VoiceMail("Local/6010@default-local-voicemail-ab0b,2", "6010@default|b") in new stack [2011-02-01 15:16:40] -- Local/6010@default-local-voicemail-ab0b,1 answered SIP/6010-00000006 [2011-02-01 15:16:40] -- Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en') [2011-02-01 15:16:40] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-01 15:16:40] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-01 15:16:40] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-01 15:16:42] -- Playing 'beep' (language 'en') [2011-02-01 15:16:42] -- Recording the message [2011-02-01 15:16:42] -- x=0, open writing: /var/spool/asterisk/voicemail/default/6010/tmp/qcVBs4 format: wav49, 0xb5901990 [2011-02-01 15:16:48] -- User hung up [2011-02-01 15:16:48] == Spawn extension (default-super, *9806010, 1) exited non-zero on 'SIP/6010-00000006' [2011-02-01 15:16:48] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-01 15:16:48] Found [2011-02-01 15:16:48] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-01 15:16:48] Found [2011-02-01 15:16:48] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-01 15:16:48] Found [2011-02-01 15:16:48] Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2011-02-01 15:16:48] Extension Changed 6010[default-local] new state Idle for Notify User 6002 [2011-02-01 15:16:48] Extension Changed 6010[default-local] new state Idle for Notify User 6010 [2011-02-01 15:16:48] == Spawn extension (default-local-voicemail, 6010, 2) exited non-zero on 'Local/6010@default-local-voicemail-ab0b,2' [2011-02-01 15:16:52] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-01 15:16:52] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-01 15:16:52] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-01 15:16:52] -- Executing [*9806010@default-super:1] Dial("SIP/6010-00000007", "Local/6010@default-local-voicemail") in new stack [2011-02-01 15:16:52] -- Called 6010@default-local-voicemail [2011-02-01 15:16:52] -- Executing [6010@default-local-voicemail:1] Set("Local/6010@default-local-voicemail-7382,2", "VMOPERATOR=Local/s@default-aa-operator-6010") in new stack [2011-02-01 15:16:52] -- Executing [6010@default-local-voicemail:2] VoiceMail("Local/6010@default-local-voicemail-7382,2", "6010@default|b") in new stack [2011-02-01 15:16:52] -- Local/6010@default-local-voicemail-7382,1 answered SIP/6010-00000007 [2011-02-01 15:16:52] -- Playing '/var/spool/asterisk/voicemail/default/6010/busy' (language 'en') [2011-02-01 15:16:53] -- Playing 'beep' (language 'en') [2011-02-01 15:16:53] -- Recording the message [2011-02-01 15:16:53] -- x=0, open writing: /var/spool/asterisk/voicemail/default/6010/tmp/jncBS2 format: wav49, 0x89787b0 [2011-02-01 15:16:59] -- User hung up [2011-02-01 15:16:59] == Spawn extension (default-super, *9806010, 1) exited non-zero on 'SIP/6010-00000007' [2011-02-01 15:16:59] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-01 15:16:59] Found [2011-02-01 15:16:59] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-01 15:16:59] Found [2011-02-01 15:16:59] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-01 15:16:59] Found [2011-02-01 15:16:59] Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2011-02-01 15:16:59] Extension Changed 6010[default-local] new state Idle for Notify User 6002 [2011-02-01 15:16:59] Extension Changed 6010[default-local] new state Idle for Notify User 6010 [2011-02-01 15:16:59] == Spawn extension (default-local-voicemail, 6010, 2) exited non-zero on 'Local/6010@default-local-voicemail-7382,2' lab*CLI> lab*CLI> lab*CLI> lab*CLI> lab*CLI> lab*CLI> lab*CLI> lab*CLI> lab*CLI> [2011-02-01 15:17:03] == Parsing '/etc/asterisk/manager.conf': [2011-02-01 15:17:03] Found [2011-02-01 15:17:03] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-01 15:17:03] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-01 15:17:03] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-01 15:17:03] -- Executing [*981@default-super:1] Goto("SIP/6010-00000008", "default-app-voicemail-main|s|1") in new stack [2011-02-01 15:17:03] -- Goto (default-app-voicemail-main,s,1) [2011-02-01 15:17:03] -- Executing [s@default-app-voicemail-main:1] NoOp("SIP/6010-00000008", "Voicemail Main") in new stack [2011-02-01 15:17:03] -- Executing [s@default-app-voicemail-main:2] Answer("SIP/6010-00000008", "") in new stack [2011-02-01 15:17:03] -- Executing [s@default-app-voicemail-main:3] AGI("SIP/6010-00000008", "agi://127.0.0.1:4573/extension") in new stack [2011-02-01 15:17:03] agi://127.0.0.1:4573/extension: Variable 'PHONE_EXTEN_HD' result is not defined!!! [2011-02-01 15:17:03] agi://127.0.0.1:4573/extension: Variable 'CHECK_HOTDESK' result is not defined!!! [2011-02-01 15:17:03] -- AGI Script agi://127.0.0.1:4573/extension completed, returning 0 [2011-02-01 15:17:03] -- Executing [s@default-app-voicemail-main:4] Wait("SIP/6010-00000008", "1") in new stack [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:5] Set("SIP/6010-00000008", "GROUP(OUTGOING)=6010") in new stack [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:6] Set("SIP/6010-00000008", "TIMEOUT(absolute)=1200") in new stack [2011-02-01 15:17:04] -- Channel will hangup at 2011-02-01 20:37:04 UTC. [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:7] Set("SIP/6010-00000008", "TIMEOUT(digit)=5") in new stack [2011-02-01 15:17:04] -- Digit timeout set to 5 [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:8] Set("SIP/6010-00000008", "TIMEOUT(response)=10") in new stack [2011-02-01 15:17:04] -- Response timeout set to 10 [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:9] Set("SIP/6010-00000008", "DBKEY=/Voicemail/default/6010") in new stack [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:10] GotoIf("SIP/6010-00000008", "0?11:14") in new stack [2011-02-01 15:17:04] -- Goto (default-app-voicemail-main,s,14) [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:14] Set("SIP/6010-00000008", "SCOPSERV_DBDEL(/Voicemail/default/6010)=") in new stack [2011-02-01 15:17:04] -- Executing [s@default-app-voicemail-main:15] VoiceMailMain("SIP/6010-00000008", "6010@default") in new stack [2011-02-01 15:17:04] -- Playing 'vm-password' (language 'en') [2011-02-01 15:17:08] NOTICE[24009]: app_voicemail.c:6112 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-01 15:17:08] -- Playing 'vm-youhave' (language 'en') [2011-02-01 15:17:09] -- Playing 'digits/3' (language 'en') [2011-02-01 15:17:09] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:17:10] -- Playing 'vm-messages' (language 'en') [2011-02-01 15:17:11] -- Playing 'vm-onefor' (language 'en') [2011-02-01 15:17:12] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:17:13] -- Playing 'vm-messages' (language 'en') [2011-02-01 15:17:14] -- Playing 'vm-first' (language 'en') [2011-02-01 15:17:14] -- Playing 'vm-message' (language 'en') [2011-02-01 15:17:15] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt': [2011-02-01 15:17:15] Found [2011-02-01 15:17:15] -- Playing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000' (language 'en') [2011-02-01 15:17:21] -- Playing 'vm-advopts' (language 'en') [2011-02-01 15:17:23] -- Playing 'vm-repeat' (language 'en') [2011-02-01 15:17:25] -- Playing 'vm-next' (language 'en') [2011-02-01 15:17:28] -- Playing 'vm-delete' (language 'en') [2011-02-01 15:17:30] -- Playing 'vm-toforward' (language 'en') [2011-02-01 15:17:30] -- Playing 'vm-deleted' (language 'en') [2011-02-01 15:17:32] -- Playing 'vm-advopts' (language 'en') [2011-02-01 15:17:34] -- Playing 'vm-repeat' (language 'en') [2011-02-01 15:17:34] -- Playing 'vm-message' (language 'en') [2011-02-01 15:17:35] -- Playing 'digits/2' (language 'en') [2011-02-01 15:17:36] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001.txt': [2011-02-01 15:17:36] Found [2011-02-01 15:17:36] -- Playing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0001' (language 'en') [2011-02-01 15:17:41] -- Playing 'vm-prev' (language 'en') [2011-02-01 15:17:44] -- Playing 'vm-deleted' (language 'en') [2011-02-01 15:17:45] -- Playing 'vm-prev' (language 'en') [2011-02-01 15:17:47] -- Playing 'vm-last' (language 'en') [2011-02-01 15:17:48] -- Playing 'vm-message' (language 'en') [2011-02-01 15:17:48] == Parsing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002.txt': [2011-02-01 15:17:48] Found [2011-02-01 15:17:48] -- Playing '/var/spool/asterisk/voicemail/default/6010/INBOX/msg0002' (language 'en') [2011-02-01 15:17:53] -- Playing 'vm-savefolder' (language 'en') [2011-02-01 15:17:55] -- Playing 'vm-press' (language 'en') [2011-02-01 15:17:56] -- Playing 'digits/0' (language 'en') [2011-02-01 15:17:57] -- Playing 'vm-for' (language 'en') [2011-02-01 15:17:57] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:17:58] -- Playing 'vm-messages' (language 'en') [2011-02-01 15:17:59] -- Playing 'vm-message' (language 'en') [2011-02-01 15:18:00] -- Playing 'digits/3' (language 'en') [2011-02-01 15:18:00] -- Playing 'vm-savedto' (language 'en') [2011-02-01 15:18:01] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:18:02] -- Playing 'vm-messages' (language 'en') [2011-02-01 15:18:03] == Parsing '/etc/asterisk/manager.conf': [2011-02-01 15:18:03] Found [2011-02-01 15:18:03] -- Playing 'vm-prev' (language 'en') [2011-02-01 15:18:05] -- Playing 'vm-advopts' (language 'en') [2011-02-01 15:18:07] == Spawn extension (default-app-voicemail-main, s, 15) exited non-zero on 'SIP/6010-00000008' [2011-02-01 15:18:07] -- Executing [h@default-app-voicemail-main:1] ResetCDR("SIP/6010-00000008", "w") in new stack [2011-02-01 15:18:07] -- Executing [h@default-app-voicemail-main:2] NoCDR("SIP/6010-00000008", "") in new stack [2011-02-01 15:18:07] -- Executing [h@default-app-voicemail-main:3] DeadAGI("SIP/6010-00000008", "agi://127.0.0.1:4573/cdr?arg1=1296591423.29&arg2=dst&arg3=*981") in new stack [2011-02-01 15:18:07] -- AGI Script agi://127.0.0.1:4573/cdr?arg1=1296591423.29&arg2=dst&arg3=*981 completed, returning 0 [2011-02-01 15:18:08] Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2011-02-01 15:18:08] Extension Changed 6010[default-local] new state Idle for Notify User 6002 [2011-02-01 15:18:08] Extension Changed 6010[default-local] new state Idle for Notify User 6010 [2011-02-01 15:18:44] == Refreshing DNS lookups. [2011-02-01 15:19:03] == Parsing '/etc/asterisk/manager.conf': [2011-02-01 15:19:03] Found [2011-02-01 15:19:32] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-01 15:19:32] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-01 15:19:32] -- Executing [*981@default-super:1] Goto("SIP/6010-00000009", "default-app-voicemail-main|s|1") in new stack [2011-02-01 15:19:32] -- Goto (default-app-voicemail-main,s,1) [2011-02-01 15:19:32] -- Executing [s@default-app-voicemail-main:1] NoOp("SIP/6010-00000009", "Voicemail Main") in new stack [2011-02-01 15:19:32] -- Executing [s@default-app-voicemail-main:2] Answer("SIP/6010-00000009", "") in new stack [2011-02-01 15:19:32] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-01 15:19:32] -- Executing [s@default-app-voicemail-main:3] AGI("SIP/6010-00000009", "agi://127.0.0.1:4573/extension") in new stack [2011-02-01 15:19:32] agi://127.0.0.1:4573/extension: Variable 'PHONE_EXTEN_HD' result is not defined!!! [2011-02-01 15:19:32] agi://127.0.0.1:4573/extension: Variable 'CHECK_HOTDESK' result is not defined!!! [2011-02-01 15:19:32] -- AGI Script agi://127.0.0.1:4573/extension completed, returning 0 [2011-02-01 15:19:32] -- Executing [s@default-app-voicemail-main:4] Wait("SIP/6010-00000009", "1") in new stack [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:5] Set("SIP/6010-00000009", "GROUP(OUTGOING)=6010") in new stack [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:6] Set("SIP/6010-00000009", "TIMEOUT(absolute)=1200") in new stack [2011-02-01 15:19:33] -- Channel will hangup at 2011-02-01 20:39:33 UTC. [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:7] Set("SIP/6010-00000009", "TIMEOUT(digit)=5") in new stack [2011-02-01 15:19:33] -- Digit timeout set to 5 [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:8] Set("SIP/6010-00000009", "TIMEOUT(response)=10") in new stack [2011-02-01 15:19:33] -- Response timeout set to 10 [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:9] Set("SIP/6010-00000009", "DBKEY=/Voicemail/default/6010") in new stack [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:10] GotoIf("SIP/6010-00000009", "0?11:14") in new stack [2011-02-01 15:19:33] -- Goto (default-app-voicemail-main,s,14) [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:14] Set("SIP/6010-00000009", "SCOPSERV_DBDEL(/Voicemail/default/6010)=") in new stack [2011-02-01 15:19:33] -- Executing [s@default-app-voicemail-main:15] VoiceMailMain("SIP/6010-00000009", "6010@default") in new stack [2011-02-01 15:19:33] -- Playing 'vm-password' (language 'en') [2011-02-01 15:19:38] -- Playing 'vm-youhave' (language 'en') [2011-02-01 15:19:39] -- Playing 'digits/1' (language 'en') [2011-02-01 15:19:39] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:19:40] -- Playing 'vm-message' (language 'en') [2011-02-01 15:19:41] -- Playing 'vm-onefor' (language 'en') [2011-02-01 15:19:42] -- Playing 'vm-INBOX' (language 'en') [2011-02-01 15:19:43] -- Playing 'vm-messages' (language 'en') [2011-02-01 15:19:44] -- Playing 'vm-opts' (language 'en') [2011-02-01 15:19:49] -- Playing 'vm-first' (language 'en') [2011-02-01 15:19:50] -- Playing 'vm-message' (language 'en') [2011-02-01 15:19:51] WARNING[25799]: app_voicemail.c:6026 play_message: No message attribute file?!! (/var/spool/asterisk/voicemail/default/6010/INBOX/msg0000.txt) [2011-02-01 15:19:51] -- Playing 'vm-advopts' (language 'en') [2011-02-01 15:19:53] -- Playing 'vm-repeat' (language 'en') [2011-02-01 15:19:54] == Spawn extension (default-app-voicemail-main, s, 15) exited non-zero on 'SIP/6010-00000009' [2011-02-01 15:19:54] -- Executing [h@default-app-voicemail-main:1] ResetCDR("SIP/6010-00000009", "w") in new stack [2011-02-01 15:19:54] -- Executing [h@default-app-voicemail-main:2] NoCDR("SIP/6010-00000009", "") in new stack [2011-02-01 15:19:54] -- Executing [h@default-app-voicemail-main:3] DeadAGI("SIP/6010-00000009", "agi://127.0.0.1:4573/cdr?arg1=1296591572.30&arg2=dst&arg3=*981") in new stack [2011-02-01 15:19:54] -- AGI Script agi://127.0.0.1:4573/cdr?arg1=1296591572.30&arg2=dst&arg3=*981 completed, returning 0