[May 25 17:59:25] -- x=0, open writing: /var/spool/asterisk/voicemail/default/2007/INBOX/msg0001-prepend format: wav, 0xb7dae958 [May 25 17:59:25] == Using SIP RTP TOS bits 184 [May 25 17:59:25] == Using SIP RTP CoS mark 5 [May 25 17:59:25] -- Executing [41440xxxxx@outbound:1] Macro("SIP/2120-0000327b", "prepnumber") in new stack [May 25 17:59:25] -- Executing [s@macro-prepnumber:1] ExecIf("SIP/2120-0000327b", "0?Set(MACRO_EXTEN=144068810)") in new stack [May 25 17:59:25] -- Executing [s@macro-prepnumber:2] GotoIf("SIP/2120-0000327b", "0?cidblock,1") in new stack [May 25 17:59:25] -- Executing [s@macro-prepnumber:3] GotoIf("SIP/2120-0000327b", "0?fixnatlprefix,1") in new stack [May 25 17:59:25] -- Executing [s@macro-prepnumber:4] Goto("SIP/2120-0000327b", "outbound,41440xxxxx,2") in new stack [May 25 17:59:25] -- Goto (outbound,41440xxxxx,2) [May 25 17:59:25] == Channel 'SIP/2120-0000327b' jumping out of macro 'prepnumber' [May 25 17:59:25] -- Executing [41440xxxxx@outbound:2] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [41440xxxxx@outbound:3] Macro("SIP/2120-0000327b", "setoutboundcdr") in new stack [May 25 17:59:25] -- Executing [s@macro-setoutboundcdr:1] GotoIf("SIP/2120-0000327b", "1?cid,1") in new stack [May 25 17:59:25] -- Goto (macro-setoutboundcdr,cid,1) [May 25 17:59:25] -- Executing [cid@macro-setoutboundcdr:1] Set("SIP/2120-0000327b", "CDR(userfield)=outbound-2120-41440xxxxx") in new stack [May 25 17:59:25] -- Executing [41440xxxxx@outbound:4] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [41440xxxxx@outbound:5] Macro("SIP/2120-0000327b", "dialoutbound,41440xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:1] Macro("SIP/2120-0000327b", "formatoutboundnum,41440xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:1] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:2] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:3] GotoIf("SIP/2120-0000327b", "0?intplus,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:4] GotoIf("SIP/2120-0000327b", "0?natl,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:5] GotoIf("SIP/2120-0000327b", "1?natl,1") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,natl,1) [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:1] GotoIf("SIP/2120-0000327b", "0?natl,4") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:2] GotoIf("SIP/2120-0000327b", "1?natl,4") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,natl,4) [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:4] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:5] GotoIf("SIP/2120-0000327b", "0?natl,7") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:6] GotoIf("SIP/2120-0000327b", "0?natl,8") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:7] Set("SIP/2120-0000327b", "ARG1=141440xxxxx") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:8] Set("SIP/2120-0000327b", "ARG1=+141440xxxxx") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:9] Goto("SIP/2120-0000327b", "return,1") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,return,1) [May 25 17:59:25] -- Executing [return@macro-formatoutboundnum:1] Set("SIP/2120-0000327b", "MACRO_RESULT=+141440xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:2] Set("SIP/2120-0000327b", "numtodial=+141440xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:3] ExecIf("SIP/2120-0000327b", "1?Set(ARG2=0)") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:4] ExecIf("SIP/2120-0000327b", "1?Set(ARG3=0)") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:5] ExecIf("SIP/2120-0000327b", "1?Set(ARG4=0)") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:6] Set("SIP/2120-0000327b", "LIMIT_WARNING_FILE=beep") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:7] Set("SIP/2120-0000327b", "LIMIT_PLAYAUDIO_CALLEE=yes") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:8] NoOp("SIP/2120-0000327b", "Fri May 25 17:59:25 2012") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:9] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:10] ExecIf("SIP/2120-0000327b", "1?Set(TOUCH_MONITOR=2120-41440xxxxx):Set(TOUCH_MONITOR=-41440xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:11] Macro("SIP/2120-0000327b", "setoutboundcid,server") in new stack [May 25 17:59:25] -- Executing [s@macro-setoutboundcid:1] GotoIf("SIP/2120-0000327b", "0?emergency,1") in new stack [May 25 17:59:25] -- Executing [s@macro-setoutboundcid:2] GotoIf("SIP/2120-0000327b", "0?6") in new stack [May 25 17:59:25] -- Executing [s@macro-setoutboundcid:3] GotoIf("SIP/2120-0000327b", "0?6") in new stack [May 25 17:59:25] -- Executing [s@macro-setoutboundcid:4] Goto("SIP/2120-0000327b", "custom,1") in new stack [May 25 17:59:25] -- Goto (macro-setoutboundcid,custom,1) [May 25 17:59:25] -- Executing [custom@macro-setoutboundcid:1] Macro("SIP/2120-0000327b", "formatoutboundnum,26254xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:1] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:2] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:3] GotoIf("SIP/2120-0000327b", "0?intplus,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:4] GotoIf("SIP/2120-0000327b", "0?natl,1") in new stack [May 25 17:59:25] -- Executing [s@macro-formatoutboundnum:5] GotoIf("SIP/2120-0000327b", "1?natl,1") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,natl,1) [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:1] GotoIf("SIP/2120-0000327b", "0?natl,4") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:2] GotoIf("SIP/2120-0000327b", "1?natl,4") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,natl,4) [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:4] GotoIf("SIP/2120-0000327b", "0?return,1") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:5] GotoIf("SIP/2120-0000327b", "0?natl,7") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:6] GotoIf("SIP/2120-0000327b", "0?natl,8") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:7] Set("SIP/2120-0000327b", "ARG1=126254xxxxx") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:8] Set("SIP/2120-0000327b", "ARG1=+126254xxxxx") in new stack [May 25 17:59:25] -- Executing [natl@macro-formatoutboundnum:9] Goto("SIP/2120-0000327b", "return,1") in new stack [May 25 17:59:25] -- Goto (macro-formatoutboundnum,return,1) [May 25 17:59:25] -- Executing [return@macro-formatoutboundnum:1] Set("SIP/2120-0000327b", "MACRO_RESULT=+126254xxxxx") in new stack [May 25 17:59:25] -- Executing [custom@macro-setoutboundcid:2] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [custom@macro-setoutboundcid:3] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [custom@macro-setoutboundcid:4] NoOp("SIP/2120-0000327b", "") in new stack [May 25 17:59:25] -- Executing [custom@macro-setoutboundcid:5] Set("SIP/2120-0000327b", "CALLERID(number)=+126254xxxxx") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:12] ExecIf("SIP/2120-0000327b", "0?Set(numtodial=9691001257)") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:13] ExecIf("SIP/2120-0000327b", "0?Set(CALLERID(number)=26254xxxxx)") in new stack [May 25 17:59:25] -- Executing [s@macro-dialoutbound:14] Dial("SIP/2120-0000327b", "SIP/+141440xxxxx@vproxy1,,W") in new stack [May 25 17:59:25] == Using SIP RTP TOS bits 184 [May 25 17:59:25] == Using SIP RTP CoS mark 5 [May 25 17:59:25] -- Called SIP/+141440xxxxx@vproxy1 [May 25 17:59:26] -- SIP/vproxy1-0000327c is making progress passing it to SIP/2120-0000327b [May 25 17:59:27] -- SIP/vproxy1-0000327c answered SIP/2120-0000327b [May 25 17:59:31] -- User ended message by pressing # [May 25 17:59:31] > Recording Format: sfmts=wav, prependfile /var/spool/asterisk/voicemail/default/2007/INBOX/msg0001-prepend, recordfile /var/spool/asterisk/voicemail/default/2007/INBOX/msg0001 [May 25 17:59:31] == Saving '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0001.txt': [May 25 17:59:31] NOTICE[2993]: app_voicemail.c:5324 copy_message: Copying message from 2007@default to 2031@default [May 25 17:59:31] -- Playing 'vm-msgsaved.gsm' (language 'en') [May 25 17:59:32] == Parsing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0002.txt': [May 25 17:59:32] == Found [May 25 17:59:32] -- Playing 'vm-message.gsm' (language 'en') [May 25 17:59:32] -- Playing 'digits/3.gsm' (language 'en') [May 25 17:59:33] -- Playing 'vm-received.gsm' (language 'en') [May 25 17:59:33] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.103:5060' - Wrong password [May 25 17:59:34] -- Playing 'digits/today.gsm' (language 'en') [May 25 17:59:35] -- Playing 'digits/at.gsm' (language 'en') [May 25 17:59:35] -- Playing 'digits/1.gsm' (language 'en') [May 25 17:59:36] -- Playing 'digits/20.gsm' (language 'en') [May 25 17:59:37] -- Playing 'digits/p-m.gsm' (language 'en') [May 25 17:59:37] -- Playing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0002.slin' (language 'en') [May 25 17:59:46] == Spawn extension (macro-dialoutbound, s, 14) exited non-zero on 'SIP/2120-0000327b' in macro 'dialoutbound' [May 25 17:59:46] == Spawn extension (outbound, 41440xxxxx, 5) exited non-zero on 'SIP/2120-0000327b' [May 25 17:59:58] -- Playing 'vm-deleted.gsm' (language 'en') [May 25 17:59:58] == Parsing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0003.txt': [May 25 17:59:58] == Found [May 25 17:59:58] -- Playing 'vm-message.gsm' (language 'en') [May 25 17:59:59] -- Playing 'digits/4.gsm' (language 'en') [May 25 17:59:59] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 25 17:59:59] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 25 18:00:00] -- Playing 'vm-received.gsm' (language 'en') [May 25 18:00:01] -- Playing 'digits/today.gsm' (language 'en') [May 25 18:00:02] -- Playing 'digits/at.gsm' (language 'en') [May 25 18:00:02] -- Playing 'digits/2.gsm' (language 'en') [May 25 18:00:03] -- Playing 'digits/oh.gsm' (language 'en') [May 25 18:00:03] -- Playing 'digits/5.gsm' (language 'en') [May 25 18:00:04] -- Playing 'digits/p-m.gsm' (language 'en') [May 25 18:00:05] -- Playing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0003.slin' (language 'en') [May 25 18:00:30] -- Playing 'vm-deleted.gsm' (language 'en') [May 25 18:00:30] == Parsing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0004.txt': [May 25 18:00:30] == Found [May 25 18:00:30] -- Playing 'vm-message.gsm' (language 'en') [May 25 18:00:31] -- Playing 'digits/5.gsm' (language 'en') [May 25 18:00:32] -- Playing 'vm-received.gsm' (language 'en') [May 25 18:00:33] -- Playing 'digits/today.gsm' (language 'en') [May 25 18:00:33] -- Playing 'digits/at.gsm' (language 'en') [May 25 18:00:34] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.103:5060' - Wrong password [May 25 18:00:34] -- Playing 'digits/3.gsm' (language 'en') [May 25 18:00:35] -- Playing 'digits/16.gsm' (language 'en') [May 25 18:00:36] -- Playing 'digits/p-m.gsm' (language 'en') [May 25 18:00:36] -- Playing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0004.slin' (language 'en') [May 25 18:00:50] -- Playing 'vm-prev.gsm' (language 'en') [May 25 18:00:51] -- Playing 'vm-undeleted.gsm' (language 'en') [May 25 18:00:51] -- Playing 'vm-last.gsm' (language 'en') [May 25 18:00:52] == Parsing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0005.txt': [May 25 18:00:52] == Found [May 25 18:00:52] -- Playing 'vm-message.gsm' (language 'en') [May 25 18:00:52] -- Playing 'vm-received.gsm' (language 'en') [May 25 18:00:53] -- Playing 'digits/today.gsm' (language 'en') [May 25 18:00:54] -- Playing 'digits/at.gsm' (language 'en') [May 25 18:00:55] -- Playing 'digits/4.gsm' (language 'en') [May 25 18:00:55] -- Playing 'digits/20.gsm' (language 'en') [May 25 18:00:56] -- Playing 'digits/5.gsm' (language 'en') [May 25 18:00:57] -- Playing 'digits/p-m.gsm' (language 'en') [May 25 18:00:58] -- Playing '/var/spool/asterisk/voicemail/default/2007/INBOX/msg0005.slin' (language 'en') [May 25 18:01:00] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 25 18:01:00] NOTICE[7498]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 25 18:01:02] -- Playing 'vm-prev.gsm' (language 'en') [May 25 18:01:04] -- Playing 'vm-undeleted.gsm' (language 'en') [May 25 18:01:04] -- Playing 'vm-nomore.gsm' (language 'en') [May 25 18:01:05] -- Playing 'vm-prev.gsm' (language 'en') [May 25 18:01:06] WARNING[2993]: file.c:766 ast_readaudio_callback: Failed to write frame skipperbuds*CLI> Disconnected from Asterisk server