[asterisk-bugs] [Asterisk 0016106]: Hangup extension executed twice in 1.6.2 RC2
Asterisk Bug Tracker
noreply at bugs.digium.com
Tue Oct 20 18:22:05 CDT 2009
A NOTE has been added to this issue.
======================================================================
https://issues.asterisk.org/view.php?id=16106
======================================================================
Reported By: ajohnson
Assigned To:
======================================================================
Project: Asterisk
Issue ID: 16106
Category: Core/General
Reproducibility: always
Severity: minor
Priority: normal
Status: feedback
Asterisk Version: 1.6.2.0-rc3
JIRA:
Regression: No
Reviewboard Link:
SVN Branch (only for SVN checkouts, not tarball releases): N/A
SVN Revision (number only!):
Request Review:
======================================================================
Date Submitted: 2009-10-20 16:09 CDT
Last Modified: 2009-10-20 18:22 CDT
======================================================================
Summary: Hangup extension executed twice in 1.6.2 RC2
Description:
When a user hangs up a call, in certain circumstances, the hangup extension
can end up being executed twice.
If I comment out this section of my dialplan, the hangup extension is only
executed once:
exten => _X.,n,Goto(s-${DIALSTATUS})
exten => s-CONGESTION,1,Congestion(5)
exten => s-BUSY,1,Busy(5)
exten => s-.,1,Congestion(5)
Otherwise, the hangup extension is executed twice. The odd thing is that
the console output does not display that the code above is being executed.
======================================================================
----------------------------------------------------------------------
(0112505) ajohnson (reporter) - 2009-10-20 18:22
https://issues.asterisk.org/view.php?id=16106#c112505
----------------------------------------------------------------------
I can't post the entire debug because it is too large, so here is the
section from the hangup:
[Oct 20 16:00:17] DEBUG[32335]: manager.c:1636 action_waitevent: Starting
waiting for an event!
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:3041 sip_alreadygone: Setting
SIP_ALREADYGONE on dialog 1891CD4E316346B49CA163F9AE348E500x0ad214c0
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:21947 stop_session_timer:
Session timer stopped: -1 - 1891CD4E316346B49CA163F9AE348E500x0ad214c0
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:20607 handle_request_bye:
Received bye, issuing owner hangup
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:3316 __sip_xmit: Trying to put
'SIP/2.0 200' onto UDP socket destined for 10.210.20.192:5060
[Oct 20 16:00:19] DEBUG[32335]: manager.c:1660 action_waitevent: Finished
waiting for an event!
[Oct 20 16:00:19] DEBUG[32333]: manager.c:1660 action_waitevent: Finished
waiting for an event!
[Oct 20 16:00:19] DEBUG[32334]: manager.c:1660 action_waitevent: Finished
waiting for an event!
[Oct 20 16:00:19] DEBUG[32286]: channel.c:4822 ast_generic_bridge: Didn't
get a frame from channel: SIP/ajohnson-b79aaaf8
[Oct 20 16:00:19] DEBUG[32286]: channel.c:5246 ast_channel_bridge: Bridge
stops bridging channels SIP/ajohnson-b79aaaf8 and SIP/sbc-09339250
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:3671 pbx_extension_helper: Launching
'NoOp'
-- Executing [h at from-test:1] NoOp("SIP/ajohnson-b79aaaf8", "Hangup!")
in new stack
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:3671 pbx_extension_helper: Launching
'Hangup'
-- Executing [h at from-test:2] Hangup("SIP/ajohnson-b79aaaf8", "") in
new stack
[Oct 20 16:00:19] DEBUG[32286]: features.c:2803 ast_bridge_call: Spawn
extension (from-test,h,2) exited non-zero on 'SIP/ajohnson-b79aaaf8'
== Spawn extension (from-test, h, 2) exited non-zero on
'SIP/ajohnson-b79aaaf8'
[Oct 20 16:00:19] DEBUG[32286]: cdr.c:1257 ast_cdr_detach: CDR detaching
from this thread
[Oct 20 16:00:19] DEBUG[32286]: channel.c:1738 ast_hangup: Hanging up
channel 'SIP/sbc-09339250'
[Oct 20 16:00:19] DEBUG[32286]: chan_sip.c:5765 sip_hangup: Hangup call
SIP/sbc-09339250, SIP callid 36bbf2625ee78fe1435dd25f6ba473c2 at 10.210.10.79
[Oct 20 16:00:19] DEBUG[32286]: chan_sip.c:3316 __sip_xmit: Trying to put
'BYE sip:602' onto UDP socket destined for 10.230.10.90:5060
[Oct 20 16:00:19] DEBUG[32286]: rtp.c:2110 ast_rtp_early_bridge: Channel
'<unspecified>' has no RTP, not doing anything
[Oct 20 16:00:19] DEBUG[32286]: app_dial.c:2230 dial_exec_full: Exiting
with DIALSTATUS=ANSWER.
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:4280 __ast_pbx_run: Spawn extension
(from-test,6026251615,1) exited non-zero on 'SIP/ajohnson-b79aaaf8'
== Spawn extension (from-test, 6026251615, 1) exited non-zero on
'SIP/ajohnson-b79aaaf8'
[Oct 20 16:00:19] DEBUG[32286]: channel.c:1633 ast_softhangup_nolock:
Soft-Hanging up channel 'SIP/ajohnson-b79aaaf8'
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:3671 pbx_extension_helper: Launching
'NoOp'
-- Executing [h at from-test:1] NoOp("SIP/ajohnson-b79aaaf8", "Hangup!")
in new stack
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:3671 pbx_extension_helper: Launching
'Hangup'
-- Executing [h at from-test:2] Hangup("SIP/ajohnson-b79aaaf8", "") in
new stack
[Oct 20 16:00:19] DEBUG[32286]: pbx.c:4423 __ast_pbx_run: Spawn extension
(from-test,h,2) exited non-zero on 'SIP/ajohnson-b79aaaf8'
== Spawn extension (from-test, h, 2) exited non-zero on
'SIP/ajohnson-b79aaaf8'
[Oct 20 16:00:19] DEBUG[32286]: channel.c:1738 ast_hangup: Hanging up
channel 'SIP/ajohnson-b79aaaf8'
[Oct 20 16:00:19] DEBUG[32286]: chan_sip.c:5765 sip_hangup: Hangup call
SIP/ajohnson-b79aaaf8, SIP callid
1891CD4E316346B49CA163F9AE348E500x0ad214c0
[Oct 20 16:00:19] DEBUG[30892]: chan_sip.c:22394 sip_devicestate: Checking
device state for peer sbc
[Oct 20 16:00:19] DEBUG[30892]: devicestate.c:460 do_state_change:
Changing state for SIP/sbc - state 1 (Not in use)
[Oct 20 16:00:19] DEBUG[30892]: devicestate.c:440 devstate_event: device
'SIP/sbc' state '1'
[Oct 20 16:00:19] DEBUG[30892]: chan_sip.c:22394 sip_devicestate: Checking
device state for peer ajohnson
[Oct 20 16:00:19] DEBUG[30900]: app_queue.c:1060 handle_statechange:
Device 'SIP/sbc' changed to state '1' (Not in use) but we don't care
because they're not a member of any queue.
[Oct 20 16:00:19] DEBUG[30892]: res_config_odbc.c:73 custom_prepare: Skip:
0; SQL: SELECT * FROM sip_peers WHERE name = ? AND host = ?
[Oct 20 16:00:19] DEBUG[30892]: res_config_odbc.c:88 custom_prepare:
Parameter 1 ('name') = 'ajohnson'
[Oct 20 16:00:19] DEBUG[30892]: res_config_odbc.c:88 custom_prepare:
Parameter 2 ('host') = 'dynamic'
[Oct 20 16:00:19] DEBUG[30892]: res_odbc.c:984 odbc_release_obj2:
odbc_release_obj2(0x92849d8) called (obj->txf = (nil))
[Oct 20 16:00:19] DEBUG[30892]: chan_sip.c:23105 build_peer: -REALTIME-
peer built. Name: ajohnson. Peer objects: 15
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:3863 __sip_ack: Stopping
retransmission on '36bbf2625ee78fe1435dd25f6ba473c2 at 10.210.10.79' of
Request 103: Match Found
[Oct 20 16:00:19] DEBUG[30892]: chan_sip.c:11674 reg_source_db: SIP
Seeding peer from astdb: 'ajohnson' at ajohnson at 10.210.20.192:5060 for 120
[Oct 20 16:00:19] DEBUG[30892]: chan_sip.c:4666 realtime_peer: -REALTIME-
loading peer from database to memory. Name: ajohnson. Peer objects: 15
[Oct 20 16:00:19] DEBUG[30892]: devicestate.c:460 do_state_change:
Changing state for SIP/ajohnson - state 1 (Not in use)
[Oct 20 16:00:19] DEBUG[30892]: devicestate.c:440 devstate_event: device
'SIP/ajohnson' state '1'
[Oct 20 16:00:19] DEBUG[30900]: app_queue.c:1060 handle_statechange:
Device 'SIP/ajohnson' changed to state '1' (Not in use) but we don't care
because they're not a member of any queue.
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:5548 sip_destroy: Destroying
SIP dialog 36bbf2625ee78fe1435dd25f6ba473c2 at 10.210.10.79
[Oct 20 16:00:19] DEBUG[30977]: chan_sip.c:5548 sip_destroy: Destroying
SIP dialog 1891CD4E316346B49CA163F9AE348E500x0ad214c0
Issue History
Date Modified Username Field Change
======================================================================
2009-10-20 18:22 ajohnson Note Added: 0112505
======================================================================
More information about the asterisk-bugs
mailing list