[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 -- 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 '' [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 ") in new stack [Aug 29 14:49:43] DEBUG[13081]: pbx.c:1662 pbx_substitute_variables_helper_full: Function result is '' [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 -- 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)