rendered paste body[Aug 29 14:49:37] DEBUG[13081]: chan_sip.c:3602 sip_answer: SIP answering channel: SIP/5025155593-088bcb68
[Aug 29 14:49:37] DEBUG[13081]: chan_sip.c:6574 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 29 14:49:37] DEBUG[13081]: sched.c:284 ast_sched_del: Attempted to delete nonexistent schedule entry 0!
[Aug 29 14:49:37] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'BackGround'
-- Executing [7115755555@lyvmail-working:2] BackGround("SIP/5025155593-088bcb68", "/var/lib/asterisk/sounds/mysounds/sounds/why-no-answer-mystery") in new stack
[Aug 29 14:49:37] DEBUG[13081]: rtp.c:2727 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Aug 29 14:49:37] DEBUG[13081]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
-- <SIP/5025155593-088bcb68> Playing '/var/lib/asterisk/sounds/mysounds/sounds/why-no-answer-mystery' (language 'en')
[Aug 29 14:49:37] DEBUG[12507]: chan_sip.c:2158 __sip_ack: Stopping retransmission on '452-1339131237@204.126.120.46' of Response 1: Match Not Found
[Aug 29 14:49:40] DEBUG[12507]: chan_sip.c:4450 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Aug 29 14:49:40] DEBUG[12507]: sched.c:284 ast_sched_del: Attempted to delete nonexistent schedule entry 0!
[Aug 29 14:49:40] DEBUG[12507]: chan_sip.c:2200 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0be5c2800272da642214448a051aada4@204.126.120.219' Request 102: Found
[Aug 29 14:49:40] DEBUG[12507]: chan_sip.c:2158 __sip_ack: Stopping retransmission on '0be5c2800272da642214448a051aada4@204.126.120.219' of Request 102: Match Not Found
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'Set'
-- Executing [7115755555@lyvmail-working:3] Set("SIP/5025155593-088bcb68", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'Set'
-- Executing [7115755555@lyvmail-working:4] Set("SIP/5025155593-088bcb68", "TIMEOUT(response)=5") in new stack
-- Response timeout set to 5
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'NoOp'
-- Executing [7115755555@lyvmail-working:5] NoOp("SIP/5025155593-088bcb68", "Getting Vmail Info") in new stack
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1662 pbx_substitute_variables_helper_full: Function result is '<sip:5025155593@204.126.120.219>'
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'NoOp'
-- Executing [7115755555@lyvmail-working:6] NoOp("SIP/5025155593-088bcb68", "Vmail info is <sip:5025155593@204.126.120.219> ") in new stack
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1662 pbx_substitute_variables_helper_full: Function result is '<sip:5025155593@204.126.120.219>'
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'Set'
-- Executing [7115755555@lyvmail-working:7] Set("SIP/5025155593-088bcb68", "Vmail_Info=5025155593@204.126.120.219>") in new stack
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1662 pbx_substitute_variables_helper_full: Function result is '5025155593'
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'Set'
-- Executing [7115755555@lyvmail-working:8] Set("SIP/5025155593-088bcb68", "Vmail_Info=5025155593") in new stack
[Aug 29 14:49:43] DEBUG[13081]: pbx.c:1809 pbx_extension_helper: Launching 'VoiceMail'
-- Executing [7115755555@lyvmail-working:9] VoiceMail("SIP/5025155593-088bcb68", "u5025155593") in new stack
[Aug 29 14:49:43] DEBUG[13081]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine.
[Aug 29 14:49:43] DEBUG[13081]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM voicemail_users WHERE mailbox = '5025155593'
[Aug 29 14:49:43] DEBUG[13081]: channel.c:3020 set_format: Set channel SIP/5025155593-088bcb68 to write format slin
-- <SIP/5025155593-088bcb68> Playing '/var/spool/asterisk/voicemail/default/5025155593/unavail' (language 'en')
[Aug 29 14:49:46] DEBUG[13081]: app_voicemail.c:2888 leave_voicemail: Hang up during prefile playback
[Aug 29 14:49:46] DEBUG[13081]: pbx.c:2407 __ast_pbx_run: Spawn extension (lyvmail-working,7115755555,9) exited non-zero on 'SIP/5025155593-088bcb68'
== Spawn extension (lyvmail-working, 7115755555, 9) exited non-zero on 'SIP/5025155593-088bcb68'
[Aug 29 14:49:46] DEBUG[13081]: channel.c:1547 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/5025155593-088bcb68'
[Aug 29 14:49:46] DEBUG[13081]: channel.c:1764 ast_hangup: Hanging up channel 'SIP/5025155593-088bcb68'
[Aug 29 14:49:46] DEBUG[13081]: chan_sip.c:3445 sip_hangup: Hangup call SIP/5025155593-088bcb68, SIP callid 452-1339131237@204.126.120.46)