Summary:	ASTERISK-11111: many retries to lock channels when using attended transfer
Reporter:	Lior M (liorm)	Labels:
Date Opened:	2007-12-26 15:55:31.000-0600	Date Closed:	2011-06-07 14:03:19
Priority:	Major	Regression?	No
Status:	Closed/Complete	Components:	Channels/General
Versions:		Frequency of Occurrence
Related Issues:
Environment:		Attachments:
Description:	A big delay before answering the call because of failures on locking a channel. The bug is reproduced in a call center after few days that the system is working and since I have started to transfer calls from the queue using the "t" option. ****** ADDITIONAL INFORMATION ****** [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Failure, could not lock '0xb1408770' after 9 retries! [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[3598] channel.c: Avoiding initial deadlock for channel '0xb1408770' [Dec 26 23:27:20] DEBUG[9272] manager.c: Manager received command 'queuestatus' [Dec 26 23:27:20] DEBUG[9273] manager.c: Manager received command 'waitevent' [Dec 26 23:27:20] DEBUG[9273] manager.c: Starting waiting for an event! [Dec 26 23:27:20] DEBUG[9273] manager.c: Finished waiting for an event! [Dec 26 23:27:20] DEBUG[9274] manager.c: Manager received command 'queuestatus' [Dec 26 23:27:20] DEBUG[9267] pbx.c: Launching 'Set' [Dec 26 23:27:20] VERBOSE[9267] logger.c:     -- Executing [9422745@from-pstn:3] Set("Zap/1-1", "initdnid=9422745") in new stack [Dec 26 23:27:20] DEBUG[9276] manager.c: Manager received command 'queuestatus' [Dec 26 23:27:20] DEBUG[9277] manager.c: Manager received command 'waitevent' [Dec 26 23:27:20] DEBUG[9277] manager.c: Starting waiting for an event! [Dec 26 23:27:20] DEBUG[9277] manager.c: Finished waiting for an event! [Dec 26 23:27:20] DEBUG[9267] pbx.c: Function result is '0574707076' [Dec 26 23:27:20] DEBUG[9267] pbx.c: Launching 'Set' [Dec 26 23:27:20] VERBOSE[9267] logger.c:     -- Executing [9422745@from-pstn:4] Set("Zap/1-1", "initcallerid=0574707076") in new stack [Dec 26 23:27:21] DEBUG[9278] manager.c: Manager received command 'queuestatus' [Dec 26 23:27:21] DEBUG[9279] manager.c: Manager received command 'waitevent' [Dec 26 23:27:21] DEBUG[9279] manager.c: Starting waiting for an event! [Dec 26 23:27:21] DEBUG[9279] manager.c: Finished waiting for an event! [Dec 26 23:27:21] DEBUG[9267] pbx.c: Function result is '10' [Dec 26 23:27:21] DEBUG[9267] pbx.c: Launching 'Set' [Dec 26 23:27:21] VERBOSE[9267] logger.c:     -- Executing [9422745@from-pstn:5] Set("Zap/1-1", "length=10") in new stack [Dec 26 23:27:21] DEBUG[9280] manager.c: Manager received command 'waitevent' [Dec 26 23:27:21] DEBUG[9280] manager.c: Starting waiting for an event! [Dec 26 23:27:21] DEBUG[9280] manager.c: Finished waiting for an event! [Dec 26 23:27:21] DEBUG[9281] manager.c: Manager received command 'queuestatus'
Comments:	By: Mark Michelson (mmichelson) 2007-12-26 16:02:21.000-0600 I highly recommend giving the latest 1.4 version a try since a lot of channel locking issues have been solved since 1.4.11. By: Russell Bryant (russell) 2007-12-26 21:49:31.000-0600 Furthermore, unless you actually have a problem with the system locking up and something not working, then this is not a problem.  The output you have provided is just debug output and does not signify a real problem.