[root@kevlab1-dtw src]# asterisk -r
Verbosity is at least 3
[Nov 19 16:02:31] WARNING[25358]: chan_sip.c:26296 build_peer: I am going to call add_peer_mwi_subs to screw you up
[Nov 19 16:02:31] NOTICE[25358]: chan_sip.c:22985 add_peer_mwi_subs: Ah Ah Ah!!!!
[Nov 19 16:02:31] WARNING[25358]: event.c:1135 ast_event_new: type '3'!
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25358]: event.c:1135 ast_event_new: type '2'!
  == Using SIP RTP CoS mark 5
    -- Executing [1699@SIP-Office-Dialplan:1] Gosub("SIP/0004F213C999-00000000", "User-Of-Voicemail,s,1") in new stack
    -- Executing [s@User-Of-Voicemail:1] Answer("SIP/0004F213C999-00000000", "") in new stack
[Nov 19 16:02:31] WARNING[25361]: event.c:1135 ast_event_new: type '2'!
[Nov 19 16:02:31] WARNING[25361]: app_voicemail.c:6772 queue_mwi_event: Calling ast_event_queue_and_cache
[Nov 19 16:02:31] WARNING[25361]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:31] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: chan_sip.c:13506 mwi_event_cb: KaBDEBUG...SIP MWI Callback
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25361]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:31] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:31] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:31] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:31] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:31] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:31] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:31] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
    -- Executing [s@User-Of-Voicemail:2] MSet("SIP/0004F213C999-00000000", "CHANNEL(language)=en") in new stack
[Nov 19 16:02:31] NOTICE[25370]: chan_sip.c:4039 sip_setoption: Unknown option: 9
    -- Executing [s@User-Of-Voicemail:3] Wait("SIP/0004F213C999-00000000", "1") in new stack
    -- Executing [s@User-Of-Voicemail:4] VoiceMailMain("SIP/0004F213C999-00000000", "6030@compuware") in new stack
    -- <SIP/0004F213C999-00000000> Playing 'vm-password.ulaw' (language 'en')
[Nov 19 16:02:34] WARNING[25370]: app_voicemail.c:9491 vm_authenticate: Unable to read password
[Nov 19 16:02:34] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:34] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:34] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:34] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:34] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:34] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:34] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:34] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:34] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:35] WARNING[25358]: chan_sip.c:26296 build_peer: I am going to call add_peer_mwi_subs to screw you up
[Nov 19 16:02:35] NOTICE[25358]: chan_sip.c:22985 add_peer_mwi_subs: Ah Ah Ah!!!!
[Nov 19 16:02:35] WARNING[25358]: event.c:1135 ast_event_new: type '3'!
[Nov 19 16:02:35] WARNING[25358]: event.c:1135 ast_event_new: type '2'!
[Nov 19 16:02:35] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:35] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:35] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:35] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:35] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:35] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:35] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:35] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:35] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:35] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:35] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:35] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:35] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:35] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:38] WARNING[25358]: chan_sip.c:26296 build_peer: I am going to call add_peer_mwi_subs to screw you up
[Nov 19 16:02:38] NOTICE[25358]: chan_sip.c:22985 add_peer_mwi_subs: Ah Ah Ah!!!!
[Nov 19 16:02:38] WARNING[25358]: event.c:1135 ast_event_new: type '3'!
[Nov 19 16:02:38] WARNING[25358]: event.c:1135 ast_event_new: type '2'!
[Nov 19 16:02:38] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:38] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:38] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:38] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:38] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:38] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:38] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:38] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:38] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:38] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:38] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:38] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:38] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:38] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:50] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:50] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:50] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:50] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:50] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:50] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:50] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:50] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:50] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
kevlab1-dtw*CLI>
[Nov 19 16:02:56] WARNING[25358]: chan_sip.c:26296 build_peer: I am going to call add_peer_mwi_subs to screw you up
[Nov 19 16:02:56] NOTICE[25358]: chan_sip.c:22985 add_peer_mwi_subs: Ah Ah Ah!!!!
[Nov 19 16:02:56] WARNING[25358]: event.c:1135 ast_event_new: type '3'!
[Nov 19 16:02:56] WARNING[25358]: event.c:1135 ast_event_new: type '2'!
  == Using SIP RTP CoS mark 5
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
    -- Executing [1699@SIP-Office-Dialplan:1] Gosub("SIP/0004F213C999-00000001", "User-Of-Voicemail,s,1") in new stack
    -- Executing [s@User-Of-Voicemail:1] Answer("SIP/0004F213C999-00000001", "") in new stack
[Nov 19 16:02:56] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:56] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:56] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:02:56] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
[Nov 19 16:02:56] WARNING[25361]: event.c:1135 ast_event_new: type '2'!
[Nov 19 16:02:56] WARNING[25361]: app_voicemail.c:6772 queue_mwi_event: Calling ast_event_queue_and_cache
[Nov 19 16:02:56] WARNING[25361]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: chan_sip.c:13506 mwi_event_cb: KaBDEBUG...SIP MWI Callback
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1420 handle_event: FOUND in the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1420 handle_event: FOUND in the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1418 handle_event: Traversing the LIST in the RWDLLIST_TRAVERSE
[Nov 19 16:02:56] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:02:56] WARNING[25345]: chan_sip.c:13506 mwi_event_cb: KaBDEBUG...SIP MWI Callback
[Nov 19 16:02:56] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:02:56] WARNING[25361]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0
    -- Executing [s@User-Of-Voicemail:2] MSet("SIP/0004F213C999-00000001", "CHANNEL(language)=en") in new stack
[Nov 19 16:02:56] NOTICE[25371]: chan_sip.c:4039 sip_setoption: Unknown option: 9
    -- Executing [s@User-Of-Voicemail:3] Wait("SIP/0004F213C999-00000001", "1") in new stack
    -- Executing [s@User-Of-Voicemail:4] VoiceMailMain("SIP/0004F213C999-00000001", "6030@compuware") in new stack
    -- <SIP/0004F213C999-00000001> Playing 'vm-password.ulaw' (language 'en')
[Nov 19 16:03:00] WARNING[25371]: app_voicemail.c:9491 vm_authenticate: Unable to read password
[Nov 19 16:03:00] WARNING[25351]: event.c:1135 ast_event_new: type '5'!
[Nov 19 16:03:00] WARNING[25351]: event.c:1392 ast_event_queue_and_cache: Before Event_Queue
[Nov 19 16:03:00] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:03:00] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:03:00] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:03:00] WARNING[25345]: event.c:1415 handle_event: Traversing the RWDLLIST in the for statement
[Nov 19 16:03:00] WARNING[25345]: event.c:1427 handle_event: Calling the callback funtion...I think
[Nov 19 16:03:00] WARNING[25345]: event.c:1412 handle_event: Event is being handled in the for statement
[Nov 19 16:03:00] WARNING[25351]: event.c:1397 ast_event_queue_and_cache: Returning the value of res 0