[mythtv-users] Myth 0.27: Weird connection problems between front/backend

[Paul Netherwood]

>>On Sun, Oct 28, 2013 at 11:45 AM, Matthias Thyroff <lists at thyroff.net> wrote:Hello list,
>>
>>I have reported this problem before, but I have not been able to fix it. I am still trouble shooting and don't really know where to look. I hope somebody can help me with a hint in the right direction.
>>
>>The problem is that sporadically, while being in the recorded programs list, or watching a recording, I get a popup message in my frontend: Mythcontext: Backend online. At the same time, the recording causing the problem is marked with the little cross showing that the recording is not available, then the cross dissapears again and the recording is shown as available, and so on... The frontend will also freeze for a couple of minutes when I leave the recording screen in this situation.
>>
>>That only indicates that the connection has been lost before and the frontend has reconnected. Looking into the console output of the frontend, I see:
>>
>>2013-10-27 16:24:51.622303 I  MythCoreContext: Connecting to backend server: 192.168.0.1:6543[http://192.168.0.1:6543] (try 1 of 1)
>>2013-10-27 16:24:58.744041 E  MythSocket(7f6a1c00b870:-1): ReadStringList: Connection died.
>>2013-10-27 16:24:58.744086 E  MythSocket(7f6a1c00b870:-1): No response.
>>2013-10-27 16:24:58.744159 N  Connection to backend server lost
>>2013-10-27 16:24:58.744321 N  Event socket closed.  No connection to the backend.
>>2013-10-27 16:24:58.744436 I  MythCoreContext: Connecting to backend server: 192.168.0.1:6543[http://192.168.0.1:6543] (try 1 of 1)
>>2013-10-27 16:24:58.747980 E  MythSocket(7f69ac00fec0:47): WriteStringList: Error, invalid string list.
>>2013-10-27 16:24:58.748023 E  MythSocket(7f69ac00fec0:47): Failed to send command.
>>2013-10-27 16:24:58.748157 C  Reconnection to backend server failed
>>2013-10-27 16:24:58.748182 E  PlaybackBoxHelper: CHECK_AVAILABILITY 'myth://192.168.0.1:6543/13120_20120403015000.mpg[http://192.168.0.1:6543/13120_20120403015000.mpg]' file not found
>>
>>(The file mentioned is available) This happens with certain recordings, not all recordings. Last time I noticed, it happened with a recording which had a description with an incorrect encoding: Umlauts were all displayed as weird character sequences, so I thought, that's the reason. I deleted the recording's description with phpmyadmin and the error went away.
>>
>>Now I have another recording which causes the problem, but here the encoding of the description is OK, umlauts are displayed correctly. Still I get the above error. (I have other recordings with incorrect encoding which do not cause the error)
>>
>>And also here, the problem went away when I deleted the program description. It went away immediately after deleting the description from the database. The description was still shown in the frontend, but the error was gone. Only after restarting the backend, the description was gone in the frontend (and the error continued fixed, for this recording) .
>>
>>This problem happens still the day I updated to 0.27.
>>
>>What can I do?
>>
>>Thanks and best regards,
>>
>>Matthias
>>
>_______________________________________________
>mythtv-users mailing list
>mythtv-users at mythtv.org[mythtv-users at mythtv.org]
>http://www.mythtv.org/mailman/listinfo/mythtv-users
>Hi Matthias -
>Did you find a solution to this problem?  I am seeing the same issue on my system.
>
>Thanks
>Dave_______________________________________________ 

I'm seeing the same issue. I'm on the latest 0.27.4 release (v0.27.4-6-ge0b0027). The frontend can sometimes lock up for several minutes. I have remote frontends but they are on the same version. Sometimes mythbackend goes to 100% CPU and is unresponsive to all clients including mythweb. It started happening since I upgraded to 0.27 and Ubuntu 14.04 (on the latest version).

On the backend I'm seeing problems keeping connected with the client. See log below.

Any one else had this problem? Any solutions or ideas?




Oct 31 18:49:14 mythbackend mythbackend: mythbackend[2292]: I TVRecEvent tv_rec.cpp:3603 (TuningCheckForHWChange) TVRec[1]: HW Tuner: 1->1
Oct 31 18:49:14 mythbackend mythbackend: mythbackend[2292]: N Scheduler autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
Oct 31 18:49:14 mythbackend mythbackend: mythbackend[2292]: I Scheduler scheduler.cpp:2689 (HandleRecordingStatusChange) Tuning recording: "Grand Designs": channel 25458 on cardid 1, sourceid 1
Oct 31 18:49:16 mythbackend mythbackend: mythbackend[2292]: I TVRecEvent tv_rec.cpp:4130 (TuningNewRecorder) TVRec[1]: rec->GetPathname(): '/data1/recordings/25458_20141031184900.mpg'
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I Scheduler scheduler.cpp:2139 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I CoreContext scheduler.cpp:704 (UpdateRecStatus) Updating status for "Grand Designs" on cardid 1 (Tuning => Recording)
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E ProcessRequest mainserver.cpp:1742 (SendResponse) SendResponse: Unable to write to client socket, as it's no longer there
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(61) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9400490:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(82) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941b368:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(75) mythsocket.cpp:745 (WriteStringListReal) MythSocket(94129f8:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(78) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940b770:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(144) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940cb98:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(136) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9411c08:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(129) mythsocket.cpp:745 (WriteStringListReal) MythSocket(94190f8:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(147) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940a258:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(135) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9419da8:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(70) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9401918:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(84) mythsocket.cpp:745 (WriteStringListReal) MythSocket(94194b0:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(153) mythsocket.cpp:745 (WriteStringListReal) MythSocket(942fd18:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(140) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941cf68:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(139) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940db30:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(130) mythsocket.cpp:745 (WriteStringListReal) MythSocket(93aa4e0:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(138) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940d250:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(61) mythsocket.cpp:745 (WriteStringListReal) MythSocket(93aa088:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythrevo4 as a client (events: 0)
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(145) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9409590:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(148) mythsocket.cpp:745 (WriteStringListReal) MythSocket(940aaf0:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(83) mythsocket.cpp:745 (WriteStringListReal) MythSocket(93d5658:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(152) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941fd60:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(134) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941af78:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(149) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941e290:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(150) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941eb70:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(133) mythsocket.cpp:745 (WriteStringListReal) MythSocket(942f3e8:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythrevo4 as a client (events: 1)
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(146) mythsocket.cpp:745 (WriteStringListReal) MythSocket(9409d70:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:00 mythbackend mythbackend: mythbackend[2292]: E MythSocketThread(131) mythsocket.cpp:745 (WriteStringListReal) MythSocket(941bca8:-1): WriteStringList: Error, called with unconnected socket.
Oct 31 19:01:15 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Playback
Oct 31 19:01:15 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythrevo4 as a client (events: 0)
Oct 31 19:01:15 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1561 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Oct 31 19:01:15 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1563 (HandleAnnounce) adding: mythrevo4 as a remote file transfer
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythbackend as a client (events: 0)
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythbackend as a client (events: 1)
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythbackend as a client (events: 0)
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 19:02:11 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythbackend as a client (events: 1)
Oct 31 19:02:27 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Playback
Oct 31 19:02:27 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythrevo4 as a client (events: 0)
Oct 31 19:02:27 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1561 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Oct 31 19:02:27 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1563 (HandleAnnounce) adding: mythrevo4 as a remote file transfer
Oct 31 19:02:27 mythbackend mythbackend: mythbackend[2292]: I ProcessRequest mainserver.cpp:1443 (HandleAnnounce) MainServer::ANN Monitor