Dennis Walker
2006-Mar-08 15:42 UTC
[Asterisk-Users] Random Zap port going crazy When channel released after a flash.
On 1.2.x I have a random problem when a Zap/x channel flashes to transfer or make a three way call. The Zap/x-2 channel is created and the transfer or three way proceeds, but on hangup the Zap/x-1 channel fails to destroy the old bridge and asterisk goes crazy logging the problem. Here is an example debug log. This happens only once a day or so, with 100 or so users transfering and three way calling all the time. Anyone having a simular problem. Thanks for you help Mar 7 11:21:29 VERBOSE[8204] logger.c: -- Starting simple switch on 'Zap/99-1' Mar 7 11:21:31 DEBUG[8204] chan_zap.c: DTMF digit: 8 on Zap/99-1 Mar 7 11:21:32 DEBUG[8204] chan_zap.c: DTMF digit: 3 on Zap/99-1 Mar 7 11:21:32 DEBUG[8204] chan_zap.c: DTMF digit: 3 on Zap/99-1 Mar 7 11:21:33 DEBUG[8204] chan_zap.c: DTMF digit: 5 on Zap/99-1 Mar 7 11:21:33 DEBUG[8204] chan_zap.c: DTMF digit: 6 on Zap/99-1 Mar 7 11:21:33 DEBUG[8204] chan_zap.c: DTMF digit: 8 on Zap/99-1 Mar 7 11:21:33 DEBUG[8204] chan_zap.c: DTMF digit: 6 on Zap/99-1 Mar 7 11:21:34 DEBUG[8204] chan_zap.c: DTMF digit: 8 on Zap/99-1 Mar 7 11:21:34 DEBUG[8204] chan_zap.c: Enabled echo cancellation on channel 99 Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Executing SetCallerID("Zap/99-1", "9377738550") in new stack Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Executing SetCallerPres("Zap/99-1", "allowed") in new stack Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Executing Dial("Zap/99-1", "Zap/G1/9373356868||Wg") in new stack Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Requested transfer capability: 0x00 - SPEECH Mar 7 11:21:34 DEBUG[25354] channel.c: Avoiding initial deadlock for 'Zap/22-1' Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Called G1/9373356868 Mar 7 11:21:34 DEBUG[25368] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/22 span 1 Mar 7 11:21:34 VERBOSE[8204] logger.c: -- Zap/22-1 is proceeding passing it to Zap/99-1 Mar 7 11:21:34 DEBUG[8204] chan_zap.c: Requested indication 15 on channel Zap/99-1 Mar 7 11:21:34 DEBUG[8204] chan_zap.c: Received AST_CONTROL_PROCEEDING on Zap/99-1 Mar 7 11:21:36 DEBUG[25368] chan_zap.c: Enabled echo cancellation on channel 22 Mar 7 11:21:36 DEBUG[25354] channel.c: Avoiding initial deadlock for 'Zap/22-1' Mar 7 11:21:36 VERBOSE[8204] logger.c: -- Zap/22-1 is ringing Mar 7 11:21:36 DEBUG[8204] chan_zap.c: Requested indication 3 on channel Zap/99-1 Mar 7 11:21:56 VERBOSE[8204] logger.c: -- Zap/22-1 answered Zap/99-1 Mar 7 11:21:56 DEBUG[8204] chan_zap.c: Requested indication -1 on channel Zap/99-1 Mar 7 11:21:56 DEBUG[8204] chan_zap.c: Took Zap/99-1 off hook Mar 7 11:21:56 VERBOSE[8204] logger.c: -- Attempting native bridge of Zap/99-1 and Zap/22-1 Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Got event Wink/Flash(3) on channel 99 (index 0) Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Winkflash, index: 0, normal: 145, callwait: -1, thirdcall: -1 Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Already have a dsp on Zap/99-2? Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Swapping 2 and 0 Mar 7 11:22:03 DEBUG[8204] chan_zap.c: disabled echo cancellation on channel 99 ----> Mar 7 11:22:03 VERBOSE[8229] logger.c: -- Starting simple switch on 'Zap/99-2' Mar 7 11:22:03 VERBOSE[8204] logger.c: -- Started three way call on channel 99 Mar 7 11:22:03 VERBOSE[8204] logger.c: -- Started music on hold, class 'default', on channel 'Zap/22-1' Mar 7 11:22:03 DEBUG[8204] channel.c: Scheduling timer at 160 sample intervals Mar 7 11:22:03 DEBUG[8204] chan_zap.c: Updated conferencing on 99, with 0 conference users Mar 7 11:22:03 DEBUG[8204] channel.c: Generator got voice, switching to phase locked mode Mar 7 11:22:03 DEBUG[8204] channel.c: Scheduling timer at 0 sample intervals Mar 7 11:22:03 DEBUG[8204] channel.c: Auto-deactivating generator Mar 7 11:22:03 VERBOSE[8204] logger.c: -- Stopped music on hold on Zap/22-1 Mar 7 11:22:03 DEBUG[8204] channel.c: Scheduling timer at 0 sample intervals ----> Mar 7 11:22:04 DEBUG[8229] chan_zap.c: DTMF digit: 8 on Zap/99-2 Mar 7 11:22:05 DEBUG[8229] chan_zap.c: DTMF digit: 3 on Zap/99-2 Mar 7 11:22:05 DEBUG[8229] chan_zap.c: DTMF digit: 3 on Zap/99-2 Mar 7 11:22:05 DEBUG[8229] chan_zap.c: DTMF digit: 5 on Zap/99-2 Mar 7 11:22:05 DEBUG[8229] chan_zap.c: DTMF digit: 6 on Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: DTMF digit: 8 on Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: DTMF digit: 6 on Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: DTMF digit: 8 on Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: Enabled echo cancellation on channel 99 Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Executing SetCallerID("Zap/99-2", "9377738550") in new stack Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Executing SetCallerPres("Zap/99-2", "allowed") in new stack Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Executing Dial("Zap/99-2", "Zap/G1/9373356868||Wg") in new stack Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Requested transfer capability: 0x00 - SPEECH Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Called G1/9373356868 Mar 7 11:22:06 DEBUG[25368] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/21 span 1 Mar 7 11:22:06 VERBOSE[8229] logger.c: -- Zap/21-1 is proceeding passing it to Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: Requested indication 15 on channel Zap/99-2 Mar 7 11:22:06 DEBUG[8229] chan_zap.c: Received AST_CONTROL_PROCEEDING on Zap/99-2 Mar 7 11:22:07 VERBOSE[8229] logger.c: -- Zap/21-1 is busy Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/21-1 Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Hangup: channel: 21 index = 0, normal = 45, callwait = -1, thirdcall = -1 Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call Mar 7 11:22:07 DEBUG[8229] chan_zap.c: disabled echo cancellation on channel 21 Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/21-1 Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Updated conferencing on 21, with 0 conference users Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/21-1 Mar 7 11:22:07 DEBUG[8229] chan_zap.c: disabled echo cancellation on channel 21 Mar 7 11:22:07 VERBOSE[8229] logger.c: -- Hungup 'Zap/21-1' Mar 7 11:22:07 VERBOSE[8229] logger.c: == Everyone is busy/congested at this time (1:1/0/0) Mar 7 11:22:07 DEBUG[8229] app_dial.c: Exiting with DIALSTATUS=BUSY. Mar 7 11:22:07 VERBOSE[8229] logger.c: -- Executing Congestion("Zap/99-2", "") in new stack Mar 7 11:22:07 DEBUG[8229] chan_zap.c: Requested indication 8 on channel Zap/99-2 Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Got event On hook(1) on channel 99 (index 0) Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Last flash was 6994 ms ago Mar 7 11:22:10 DEBUG[8229] channel.c: Planning to masquerade channel Zap/22-1 into the structure of Zap/99-2 Mar 7 11:22:10 DEBUG[8229] channel.c: Done planning to masquerade channel Zap/22-1 into the structure of Zap/99-2 Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Swapping 2 and 0 Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Released sub 2 of channel 99 Mar 7 11:22:10 DEBUG[8229] channel.c: Got clone lock for masquerade on 'Zap/22-1' at 0x829a05c Mar 7 11:22:10 DEBUG[8229] chan_zap.c: New owner for channel 99 is Zap/22-1<MASQ> Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Updated conferencing on 99, with 0 conference users Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Hangup: channel: 99 index = -1, normal = 145, callwait = -1, thirdcall = -1 Mar 7 11:22:10 VERBOSE[8229] logger.c: -- Hungup 'Zap/22-1<MASQ>' Mar 7 11:22:10 DEBUG[8229] channel.c: Putting channel Zap/22-1 in 64/64 formats Mar 7 11:22:10 DEBUG[8229] chan_zap.c: New owner for channel 22 is Zap/22-1 Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Updated conferencing on 22, with 0 conference users Mar 7 11:22:10 DEBUG[8229] chan_zap.c: Updated conferencing on 22, with 0 conference users Mar 7 11:22:10 DEBUG[8229] channel.c: Released clone lock on 'Zap/99-2<ZOMBIE>' Mar 7 11:22:10 DEBUG[8229] channel.c: Done Masquerading Zap/22-1 (6) Mar 7 11:22:10 DEBUG[8204] channel.c: Bridge stops because we're zombie or need a soft hangup: c0=Zap/99-1, c1=Zap/99-2<ZOM BIE>, flags: No,No,Yes,Yes Mar 7 11:22:10 DEBUG[8204] channel.c: Bridge stops bridging channels Zap/99-1 and Zap/99-2<ZOMBIE> Mar 7 11:22:10 DEBUG[8204] app_dial.c: Exiting with DIALSTATUS=ANSWER. Mar 7 11:22:10 VERBOSE[8204] logger.c: -- Executing Congestion("Zap/99-1", "") in new stack Mar 7 11:22:10 DEBUG[8204] chan_zap.c: Requested indication 8 on channel Zap/99-1 Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99 Mar 7 11:22:11 WARNING[8204] chan_zap.c: We're Zap/99-1, not ^F^X<AD><ZOMBIE> Mar 7 11:22:11 DEBUG[8204] chan_zap.c: Exception on 145, channel 99