pthread_mutex_trylock in DCERouter.log

[seagullarity]

I was getting a flood of error messages in /var/log/pluto/DCERouter.log that looked like the following:

01      05/27/11 16:45:32.738           pthread_mutex_trylock returned 22  (0xaac1dcb8) (>10245) Media Plugin: MythBackEnd_Socket.cpp:132  <0x98bfeb90>

I had to scroll up to see what was above the flood of error messages. In my case, it was being caused by VDR. Since I don't use VDR, I ran "sudo apt-get remove vdr" and the problem was fixed.

[coley]

I got the same error, but reported in a diff location in media plugin. How did you conclude VDR was causing the problem? thought an address reported looked a bit funky but when everything was going wrong didn't pin it on vdr.
Did your router work at all when those errors were being logged?

-Coley.

[seagullarity]

My orbiter would be going through the initialization routine (with the large text statements flashing on the screen), and before it got to the point that it actually loaded the on-screen orbiter, it hung at a black screen. I had to close X windows (ctrl-alt-backspace) since I couldn't change terminals for some reason, and that's how I was able to view the logs. I didn't see DCERouter doing anything else as I scrolled through about ten thousand lines of that error message.

I then cleared DCERouter.log, and restarted the Core. I could then see that above the spewing of error messages was a call to VDR, which looked like below:

Code: [Select]

#
08      05/27/11 18:28:01.782           Received Message from 11 (^[[36;1mTelecom Plug-in / Tech Closet^[[0m) to 18 (^[[36;1mAsterisk / Tech Closet^[[0m), type 1 id 922 Command:^[[35;1mSend Asterisk Status^[[0m, retry retry, parameters: <0xa6ea6b90>
#
05      05/27/11 18:28:01.782           ^[[33;1mThe target device 18 (routed to 18) has not registered.^[[0m <0xb64bbb90>
#
01      05/27/11 18:28:01.805           ^[[31;1mStarting Hulu_Plugin::Register()^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:01.806           ^[[31;1mRegistered device 2102^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:01.818           ^[[31;1mattempting to use un-initialized lock (0x95b1594) (>5103) ÃÃK¶8ÃK¶ÃÃK¶^A: VDRPlugin.cpp:1045 ^[[0m <0xb64bc6c05      05/27/11 18:28:02.815           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:03.815           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:04.815           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:04.815           ^[[33;1mClientSocket::Connect() not successful^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:04.815           ^[[33;1mvoid ClientSocket::Disconnect() on this socket: 0xbf86cb18 (m_Socket: 141)^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:04.815           ^[[31;1mVDRPlugin::BuildChannelList cannot get channel list^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:04.815           ^[[31;1mattempting to use un-initialized lock (0x95b1594) (>5144) ÃÃK¶8ÃK¶ÃÃK¶^A: VDRPlugin.cpp:1313 ^[[0m <0xb64bc6c01      05/27/11 18:28:04.815           ^[[31;1mQuery failed (Table 'pluto_main.Bookmark' doesn't exist): SELECT PK_Bookmark,EK_Users,Position FROM Bookmark WHERE EK_MediaType=1 (1)^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:04.815           ^[[33;1mResetting db_wrapper connection^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:04.815           ^[[31;1mQuery failed (Table 'pluto_main.Bookmark' doesn't exist): SELECT PK_Bookmark,EK_Users,Position FROM Bookmark WHERE EK_MediaType=1 (1)^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:04.815           ^[[33;1mResetting db_wrapper connection^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:04.815           ^[[31;1mQuery failed (): SELECT PK_Bookmark,EK_Users,Position FROM Bookmark WHERE EK_MediaType=1 retry: (nil)^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:04.815           ^[[31;1mattempting to use un-initialized lock (0x95b1594) (>5149) ÃÃK¶8ÃK¶ÃÃK¶^A: VDRPlugin.cpp:1425 ^[[0m <0xb64bc6c
#
05      05/27/11 18:28:05.817           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:06.815           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:07.815           ^[[33;1mConnect() failed, Error Code 111 (Connection refused))^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:07.815           ^[[33;1mClientSocket::Connect() not successful^[[0m <0xb64bc6c0>
#
05      05/27/11 18:28:07.815           ^[[33;1mvoid ClientSocket::Disconnect() on this socket: 0xbf86ccc8 (m_Socket: 141)^[[0m <0xb64bc6c0>
#
01      05/27/11 18:28:16.001           ^[[31;1mpthread_mutex_trylock returned 22  (0x95b1598) (>5334) Media Plugin: Media_Plugin.cpp:6314 ^[[0m <0xa7ea8b90>
#
01      05/27/11 18:28:17.011           ^[[31;1mpthread_mutex_trylock returned 22  (0x95b1598) (>5334) Media Plugin: Media_Plugin.cpp:6314 ^[[0m <0xa7ea8b90>
That's how I figured out that it was VDR.

[coley]

Looks like this was fixed. Did and apt-get update/upgrade and a new pluto-std-plugins pkg was installed, or maybe it didn't install correctly the previous time, but a quick reboot later and DCERouter was operational again.

-Coley.