[Home]

Summary:ASTERISK-19922: AudioHook mutex errors when Local channel is optimized away
Reporter:Sebastian Gutierrez (sum)Labels:
Date Opened:2012-05-29 12:50:51Date Closed:2012-09-17 14:36:16
Priority:MajorRegression?
Status:Closed/CompleteComponents:Channels/chan_local
Versions:1.8.10.1 Frequency of
Occurrence
Occasional
Related
Issues:
Environment:CentOs 6.2 asterisk-1.8.10Attachments:( 0) dialplan.rtf
( 1) fulldebug.zip
Description:This messages start appearing:

after that I have to restart

[May 28 15:46:19] ERROR[28099]: lock.c:438 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): Error releasing mutex: Operation not permitted
[May 28 15:46:19] ERROR[28099]: lock.c:280 __ast_pthread_mutex_lock: audiohook.c line 688 (audio_audiohook_write_list): Error obtaining mutex: State not recoverable
[May 28 15:46:19] ERROR[28099]: lock.c:407 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): mutex '&(audiohook)->lock' freed more times than we've locked!
[May 28 15:46:19] ERROR[28099]: lock.c:438 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): Error releasing mutex: Operation not permitted
[May 28 15:46:19] ERROR[28099]: lock.c:280 __ast_pthread_mutex_lock: audiohook.c line 688 (audio_audiohook_write_list): Error obtaining mutex: State not recoverable
[May 28 15:46:19] ERROR[28099]: lock.c:407 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): mutex '&(audiohook)->lock' freed more times than we've locked!
[May 28 15:46:19] ERROR[28099]: lock.c:438 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): Error releasing mutex: Operation not permitted
[May 28 15:46:19] ERROR[28099]: lock.c:280 __ast_pthread_mutex_lock: audiohook.c line 688 (audio_audiohook_write_list): Error obtaining mutex: State not recoverable
[May 28 15:46:19] ERROR[28099]: lock.c:407 __ast_pthread_mutex_unlock: audiohook.c line 705 (audio_audiohook_write_list): mutex '&(audiohook)->lock' freed more times than we've locked!


more info will be added as soon Im able to collect it.
Comments:By: Rusty Newton (rnewton) 2012-05-30 15:58:12.486-0500

Pending more information from reporter.

By: Sebastian Gutierrez (sum) 2012-05-30 16:58:09.201-0500

I attach full debug,

By: Sebastian Gutierrez (sum) 2012-05-30 16:59:41.242-0500

in full debug at May 30 15:17, you can see the problem start ocurring, when I saw it I switch to core set debug 100, when this happens phones cant register, and new calls cant be made.
requieres a restart, this happens more than ones a day

By: Matt Jordan (mjordan) 2012-06-04 08:12:15.616-0500

What happens if you fix the error just prior to the audiohook locking error messages:

[May 30 15:17:05] WARNING[6867] app_queue.c: Missing argument to UpdateQueue (queuename,uniqueid,membername,status,talktime,params[totaltime,callednumber])

By: Sebastian Gutierrez (sum) 2012-06-04 08:16:43.760-0500

same thing, thats simply a warning that not all the parameters were passed to QueueUpdate, as not all them are passed then QueueUpdate wont execute. if you want to take a look at the code of QueueUpdate you can check ASTERISK-19862

By: Matt Jordan (mjordan) 2012-06-04 08:20:47.434-0500

Okay, just wanted to make sure.

Do you mind posting the relevant portion of the dialplan?  Specifically, extension 2604 and the h extension in the same context would be great.

By: Sebastian Gutierrez (sum) 2012-06-04 08:25:36.536-0500

dialplan attached

By: Sebastian Gutierrez (sum) 2012-09-17 14:36:16.844-0500

moved to 10 this is not seen on that version