MythBackend Unstable Connection

Have a MythTV related problem? Ask for help from other MythTV users here.

Moderator: Forum Moderators

Post Reply
DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

MythBackend Unstable Connection

Post by DerekdeClercq » Fri Apr 06, 2018 9:44 am

I need some help please as I have no idea what more to check after going through a lot of the posts.

I have a master-backend server and a seperate frontend server. The frontend is able to connect to the backend to display the videos available. But seems to loose the connection when I try to play. If I keep on clicking play it will play the video after 5 to 10 attempts and then play the video without any problems. It seems to be limited to starting the playback. I have noticed some error messages on both the backend and frontend which confuses me

I have check the ip address configuration on the backend and front end and it is correct. Really going crazy here

Backend log

Code: Select all

Apr  6 11:35:06 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(da3180:-1): No response.
Apr  6 11:35:06 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:35:06 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(43) mainserver.cpp:7693 (connectionClosed) Control sock(dfaf40) disconnected
Apr  6 11:35:07 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:35:07 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:35:37 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(dfeed0:45): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:35:37 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xdfeed0)
Apr  6 11:35:37 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(dfeed0:-1): No response.
Apr  6 11:35:37 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:35:37 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(45) mainserver.cpp:7693 (connectionClosed) Control sock(da3c60) disconnected
Apr  6 11:35:38 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:35:38 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:36:08 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(da2040:47): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:36:08 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xda2040)
Apr  6 11:36:08 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(da2040:-1): No response.
Apr  6 11:36:08 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:36:08 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(37) mainserver.cpp:7693 (connectionClosed) Control sock(da2040) disconnected
Apr  6 11:36:09 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:36:09 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:36:39 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(e00e30:39): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:36:39 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xe00e30)
Apr  6 11:36:39 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(e00e30:-1): No response.
Apr  6 11:36:39 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:36:39 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(39) mainserver.cpp:7693 (connectionClosed) Control sock(da9fc0) disconnected
Apr  6 11:36:40 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:36:40 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:37:10 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(ddfc50:41): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:37:10 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xddfc50)
Apr  6 11:37:10 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(ddfc50:-1): No response.
Apr  6 11:37:10 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:37:10 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(37) mainserver.cpp:7693 (connectionClosed) Control sock(ddfc50) disconnected
Apr  6 11:37:11 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:37:11 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:37:41 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(de5820:39): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:37:41 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xde5820)
Apr  6 11:37:41 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(de5820:-1): No response.
Apr  6 11:37:41 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:37:41 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(37) mainserver.cpp:7693 (connectionClosed) Control sock(de5820) disconnected
Apr  6 11:37:42 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:37:42 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:38:12 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(da3c60:39): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:38:12 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xda3c60)
Apr  6 11:38:12 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(da3c60:-1): No response.
Apr  6 11:38:12 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:38:12 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(39) mainserver.cpp:7693 (connectionClosed) Control sock(dace10) disconnected
Apr  6 11:38:13 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:38:13 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully
Apr  6 11:38:43 frikkie mythbackend: mythbackend[4628]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(dbbbf0:41): ReadStringList: Error, timed out after 30000 ms.
Apr  6 11:38:43 frikkie mythbackend: mythbackend[4628]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0xdbbbf0)
Apr  6 11:38:43 frikkie mythbackend: mythbackend[4628]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(dbbbf0:-1): No response.
Apr  6 11:38:43 frikkie mythbackend: mythbackend[4628]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr  6 11:38:43 frikkie mythbackend: mythbackend[4628]: I MythSocketThread(41) mainserver.cpp:7693 (connectionClosed) Control sock(d9fe50) disconnected
Apr  6 11:38:44 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr  6 11:38:44 frikkie mythbackend: mythbackend[4628]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully

Code: Select all

Apr  6 11:43:23 multimedia mythfrontend.real: mythfrontend[1854]: N CoreContext mythmainwindow.cpp:2963 (PauseIdleTimer) Suspending idle timer
Apr  6 11:43:23 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:1094 (TV) TV::TV(): Creating TV object
Apr  6 11:43:23 multimedia mythfrontend.real: mythfrontend[1854]: N CoreContext mythmainwindow.cpp:2963 (PauseIdleTimer) Suspending idle timer
Apr  6 11:43:23 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:1316 (Init) TV::Init(): Created TvPlayWindow.
Apr  6 11:43:23 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:2277 (HandleStateChange) TV::HandleStateChange(): Attempting to change from None to WatchingVideo
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(dcab4b0:42): ReadStringList: Error, timed out after 5000 ms.
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: C CoreContext mythcorecontext.cpp:1636 (CheckProtoVersion) Protocol version check failure.#012#011#011#011The response to MYTH_PROTO_VERSION was empty.#012#011#011#011This happens when the backend is too busy to respond,#012#011#011#011or has deadlocked due to bugs or hardware failure.
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: E CoreContext remotefile.cpp:177 (openSocket) RemoteFile::openSocket(control socket): Failed validation to server frikkie:6543
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: E CoreContext fileringbuffer.cpp:373 (OpenFile) FileRingBuf(myth://Videos@frikkie/Movies/1968/Blackbeard's Ghost/1 - Blackbeard's Ghost.avi): RingBuffer::RingBuffer(): Failed to open remote file (myth://Videos@frikkie/Movies/1968/Blackbeard's Ghost/1 - Blackbeard's Ghost.avi)
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:2640 (HandleStateChange) TV::HandleStateChange(): Main UI disabled.
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:426 (StartTV) TV::StartTV(): Entering main playback loop.
Apr  6 11:43:28 multimedia mythfrontend.real: mythfrontend[1854]: I CoreContext tv_play.cpp:428 (StartTV) TV::StartTV(): Exiting main playback loop.
Apr  6 11:43:29 multimedia mythfrontend.real: mythfrontend[1854]: N CoreContext mythmainwindow.cpp:2968 (PauseIdleTimer) Resuming idle timer
Apr  6 11:43:29 multimedia mythfrontend.real: mythfrontend[1854]: N CoreContext mythmainwindow.cpp:2968 (PauseIdleTimer) Resuming idle timer

User avatar
pgbennett
Developer
Posts: 233
Joined: Mon Apr 27, 2015 5:41 pm
United States of America

Re: MythBackend Unstable Connection

Post by pgbennett » Fri Apr 06, 2018 11:07 pm

It seems your backend is not configured correctly. The backend is trying to connect to the master server although it is itself the master server. Use mythtv-setup on the backend to make sure it is set up as the master backend.

You did not mention which version of MythTV you are running. The master backend setup is different in Version 29 from prior versions. If you are still having problems let me know which version you are using.

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Mon Apr 09, 2018 7:46 am

Thank you for the feedback!

I do have the master backend checkbox selected Under General > Host Address and Backend Setup > This server is the master backend on the back end
The versions are
2018-04-09 09:42:37.992549 C mythbackend version: fixes/29 [v29.1-12-g60e40b3] www.mythtv.org
Apr 6 10:09:14onfigureLogging) mythfrontend version: fixes/29 [v29.1-12-g60e40b3] www.mythtv.org

Am I perhaps setting it up at the wrong place?

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Wed Apr 11, 2018 7:54 am

Is there possibly a place to turn on more detailed logging, like a trace level. I am hoping that if I can do that I might be able to find which setting I have got wrong?

User avatar
pvr4me
Senior
Posts: 611
Joined: Fri Feb 07, 2014 7:25 pm
Location: near Toronto, Canada
Contact:
Canada

Re: MythBackend Unstable Connection

Post by pvr4me » Wed Apr 11, 2018 2:11 pm

DerekdeClercq wrote:
Wed Apr 11, 2018 7:54 am
Is there possibly a place to turn on more detailed logging, like a trace level. I am hoping that if I can do that I might be able to find which setting I have got wrong?
See --help output or:

https://www.mythtv.org/wiki/Logging

Craig
The MacPorts guy.

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Thu Apr 12, 2018 8:01 am

Thanks Craig

I have tried that one but I suspected I am just not understanding what it is telling me :/. I was hoping for something like --setloglevel debug(connection.*) or something to try and target specifically the connection related events? I think I just don't know enough about how it works to spot the specific error in between all the information presented

User avatar
pvr4me
Senior
Posts: 611
Joined: Fri Feb 07, 2014 7:25 pm
Location: near Toronto, Canada
Contact:
Canada

Re: MythBackend Unstable Connection

Post by pvr4me » Thu Apr 12, 2018 1:33 pm

'--verbose' controls which myth activities will be logged.

Run mythbackend -v help to get a list of all the verbose options. I think the one you want is "network" but I could be wrong. Note that you can log more than one area at once, eg '--verbose network,socket,general'

'--loglevel' determines how detailed the logging messages will be; "debug" is the most detailed possible.

Eg --loglevel debug

The wiki says that both can be set at the same time though I've never tried that. Eg:

Code: Select all

mythbackend --setverbose upnp:debug,http:notice,record:debug
Finally, you may be able to change the loglevel and verbose settings without stopping/restarting the backend. You may have to switch users to the userid running the backend, though.

Craig
Bonus: the same logging options are generally available to all myth* programs. Eg mythfrontend, mythutil, mythfilldatabase, ...
The MacPorts guy.

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Mon Apr 16, 2018 12:08 pm

Hey

I managed to run the command and get the following. It seems not to be able to connect, but when I test the connection it connect fine and the ip address is correct. I am running it as root to try and make sure there are no security problems either but that makes no difference, I have run it as the mythtv user as well but get the same error

Please can you help me with what I am doing wrong

Code: Select all

root@frikkie:~# mythbackend --setverbose upnp:debug,http:notice,record:debug
2018-04-16 14:01:37.188108 C  mythbackend version: fixes/29 [v29.1-12-g60e40b3] www.mythtv.org
2018-04-16 14:01:37.188132 C  Qt version: compile: 5.5.1, runtime: 5.5.1
2018-04-16 14:01:37.188135 N  Enabled verbose msgs:  general
2018-04-16 14:01:37.188146 N  Setting Log Level to LOG_INFO
2018-04-16 14:01:37.199130 I  Added logging to the console
2018-04-16 14:01:37.199323 I  Setup Interrupt handler
2018-04-16 14:01:37.199331 I  Setup Terminated handler
2018-04-16 14:01:37.199341 I  Setup Segmentation fault handler
2018-04-16 14:01:37.199350 I  Setup Aborted handler
2018-04-16 14:01:37.199356 I  Setup Bus error handler
2018-04-16 14:01:37.199365 I  Setup Floating point exception handler
2018-04-16 14:01:37.199371 I  Setup Illegal instruction handler
2018-04-16 14:01:37.199381 I  Setup Real-time signal 0 handler
2018-04-16 14:01:37.199389 I  Setup Hangup handler
2018-04-16 14:01:37.199478 N  Using runtime prefix = /usr
2018-04-16 14:01:37.199481 N  Using configuration directory = /root/.mythtv
2018-04-16 14:01:37.199545 I  Assumed character encoding: en_ZA.utf8
2018-04-16 14:01:37.199553 W  This application expects to be running a locale that specifies a UTF-8 codeset, and many features may behave improperly with your current language settings. Please set the LC_ALL or LC_CTYPE, and LANG variable(s) in the environment in which this program is executed to include a UTF-8 codeset (such as 'en_US.UTF-8').
2018-04-16 14:01:37.199781 N  Empty LocalHostName.
2018-04-16 14:01:37.199790 I  Using localhost value of frikkie
2018-04-16 14:01:37.199820 I  Start up testing connections. DB frikkie.home.ddc, BE , attempt 0, status dbAwake
2018-04-16 14:01:38.219160 N  Setting QT default locale to en_US
2018-04-16 14:01:38.219177 I  Current locale en_US
2018-04-16 14:01:38.219229 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-04-16 14:01:38.228943 I  Loading en_gb translation for module mythfrontend
2018-04-16 14:01:38.233226 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.2:6543 (try 1 of 1)
2018-04-16 14:01:45.257022 E  MythSocket(25f6f20:12): ReadStringList: Error, timed out after 7000 ms.
2018-04-16 14:01:45.257175 C  Protocol version check failure.
			The response to MYTH_PROTO_VERSION was empty.
			This happens when the backend is too busy to respond,
			or has deadlocked due to bugs or hardware failure.
2018-04-16 14:01:45.257403 E  Unable to connect to backend, verbose mask unchanged 
root@frikkie:~# telnet 192.168.0.2 6543
Trying 192.168.0.2...
Connected to 192.168.0.2.
Escape character is '^]'.
^]
telnet> quit
Connection closed.


User avatar
pvr4me
Senior
Posts: 611
Joined: Fri Feb 07, 2014 7:25 pm
Location: near Toronto, Canada
Contact:
Canada

Re: MythBackend Unstable Connection

Post by pvr4me » Mon Apr 16, 2018 1:03 pm

I'm not very familiar with your OS, but...

Is "frikkie" at 192.168.0.2 ?

On frikkie, what are the contents of /root/.mythtv/config.xml ?

Use top to check on frikkie that you are only running 1 copy of mythbackend. When you reboot the machine, does one copy come up automatically? You ran the mythbackend command as root; I think it would normally be run as user "mythtv".

Craig
The MacPorts guy.

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Mon Apr 16, 2018 2:25 pm

Thanks for the reply!

Yes there is only one instance running as mythtv. I did try the earlier command as mythtv first, but just use root because it seems to smash through any security issues in general and wanted to make sure that was not my problem.

On reboot only one starts, it registers a service called "mythtv-backend" which came with the package and stops/starts the service as mythtv

Code: Select all

derek@frikkie:~$ ps -Af | grep myth
mythtv    3499     1  0 14:00 ?        00:00:13 /usr/bin/mythbackend --quiet --syslog local7
derek     6334  6267  0 16:24 pts/1    00:00:00 grep --color=auto myth
derek@frikkie:~$
Yes, "frikkie" is my master backend at:

Code: Select all

derek@frikkie:~$ ifconfig
enp7s0    Link encap:Ethernet  HWaddr e8:94:f6:03:12:86
          inet addr:192.168.0.2  Bcast:192.168.0.255  Mask:255.255.255.0
          inet6 addr: fe80::ea94:f6ff:fe03:1286/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:54036059 errors:0 dropped:932 overruns:0 frame:0
          TX packets:21054384 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:70118001702 (70.1 GB)  TX bytes:17112480611 (17.1 GB)
and "multimedia" is the front end at

Code: Select all

derek@multimedia:~$ ifconfig
eno1      Link encap:Ethernet  HWaddr ec:a8:6b:fe:a7:0b
          inet addr:192.168.0.7  Bcast:192.168.0.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4704018 errors:0 dropped:0 overruns:0 frame:0
          TX packets:819561 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:4762649050 (4.7 GB)  TX bytes:160330602 (160.3 MB)
          Interrupt:20 Memory:f7d00000-f7d20000

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Mon Apr 16, 2018 2:28 pm

On a hint, because the front end works if you press play multiple times consecutively I did the same for the command you gave and it gave this feedback eventuallly

Code: Select all

2018-04-16 16:27:24.559481 C  Protocol version check failure.
                        The response to MYTH_PROTO_VERSION was empty.
                        This happens when the backend is too busy to respond,
                        or has deadlocked due to bugs or hardware failure.
2018-04-16 16:27:24.559744 E  Unable to connect to backend, verbose mask unchanged
root@frikkie:~# mythbackend --setverbose upnp:debug,http:notice,record:debug
2018-04-16 16:27:37.239846 C  mythbackend version: fixes/29 [v29.1-12-g60e40b3] www.mythtv.org
2018-04-16 16:27:37.239870 C  Qt version: compile: 5.5.1, runtime: 5.5.1
2018-04-16 16:27:37.239873 N  Enabled verbose msgs:  general
2018-04-16 16:27:37.239888 N  Setting Log Level to LOG_INFO
2018-04-16 16:27:37.250726 I  Added logging to the console
2018-04-16 16:27:37.250910 I  Setup Interrupt handler
2018-04-16 16:27:37.250920 I  Setup Terminated handler
2018-04-16 16:27:37.250926 I  Setup Segmentation fault handler
2018-04-16 16:27:37.250934 I  Setup Aborted handler
2018-04-16 16:27:37.250940 I  Setup Bus error handler
2018-04-16 16:27:37.250947 I  Setup Floating point exception handler
2018-04-16 16:27:37.250953 I  Setup Illegal instruction handler
2018-04-16 16:27:37.250962 I  Setup Real-time signal 0 handler
2018-04-16 16:27:37.250969 I  Setup Hangup handler
2018-04-16 16:27:37.251052 N  Using runtime prefix = /usr
2018-04-16 16:27:37.251056 N  Using configuration directory = /root/.mythtv
2018-04-16 16:27:37.251154 I  Assumed character encoding: en_ZA.utf8
2018-04-16 16:27:37.251166 W  This application expects to be running a locale that specifies a UTF-8 codeset, and many features may behave improperly with your current language settings. Please set the LC_ALL or LC_CTYPE, and LANG variable(s) in the environment in which this program is executed to include a UTF-8 codeset (such as 'en_US.UTF-8').
2018-04-16 16:27:37.251482 N  Empty LocalHostName.
2018-04-16 16:27:37.251494 I  Using localhost value of frikkie
2018-04-16 16:27:37.251534 I  Start up testing connections. DB frikkie.home.ddc, BE , attempt 0, status dbAwake
2018-04-16 16:27:38.269153 N  Setting QT default locale to en_US
2018-04-16 16:27:38.269165 I  Current locale en_US
2018-04-16 16:27:38.269213 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2018-04-16 16:27:38.280836 I  Loading en_gb translation for module mythfrontend
2018-04-16 16:27:38.285127 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.0.2:6543 (try 1 of 1)
2018-04-16 16:27:39.200591 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2018-04-16 16:27:39.202086 I  Sent 'SET_VERBOSE upnp:debug,http:notice,record:debug' message
2018-04-16 16:27:39.202998 I  Waiting for threads to exit.

DerekdeClercq
Newcomer
Posts: 11
Joined: Wed Jul 15, 2015 2:08 pm
South Africa

Re: MythBackend Unstable Connection

Post by DerekdeClercq » Mon Apr 16, 2018 2:32 pm

with the more detailed logging I am seeing a lot of these messages before the connect timeout bit. It seems to be looking for something on another subnet but I can't ping it or know what is at 192.168.88.203/196.168.88.202?

Code: Select all

Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:658 (ProcessNotify) SSDP::ProcessNotify ...#012DescURL=http://192.168.88.203:65530/root.sxml#012NTS    =ssdp:alive#012NT     =urn:schemas-upnp-org:service:WANIPConnection:1#012USN    =uuid:bc329e00-1dd8-11b2-8601-ccb255d51490::urn:schemas-upnp-org:service:WANIPConnection:1#012Cache  =max-age=60
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:407 (ProcessData) SSDP::ProcessData - requestLine: NOTIFY * HTTP/1.1
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:658 (ProcessNotify) SSDP::ProcessNotify ...#012DescURL=http://192.168.88.203:65530/root.sxml#012NTS    =ssdp:alive#012NT     =urn:schemas-upnp-org:service:Layer3Forwarding:1#012USN    =uuid:bc329e00-1dd8-11b2-8601-ccb255d5148e::urn:schemas-upnp-org:service:Layer3Forwarding:1#012Cache  =max-age=60
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:407 (ProcessData) SSDP::ProcessData - requestLine: NOTIFY * HTTP/1.1
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:658 (ProcessNotify) SSDP::ProcessNotify ...#012DescURL=http://192.168.88.203:65530/root.sxml#012NTS    =ssdp:alive#012NT     =uuid:bc329e00-1dd8-11b2-8601-ccb255d5148e#012USN    =uuid:bc329e00-1dd8-11b2-8601-ccb255d5148e#012Cache  =max-age=60
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:407 (ProcessData) SSDP::ProcessData - requestLine: NOTIFY * HTTP/1.1
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:658 (ProcessNotify) SSDP::ProcessNotify ...#012DescURL=http://192.168.88.203:65530/root.sxml#012NTS    =ssdp:alive#012NT     =uuid:bc329e00-1dd8-11b2-8601-ccb255d5148f#012USN    =uuid:bc329e00-1dd8-11b2-8601-ccb255d5148f#012Cache  =max-age=60
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:407 (ProcessData) SSDP::ProcessData - requestLine: NOTIFY * HTTP/1.1
Apr 16 16:31:11 frikkie mythbackend: mythbackend[6496]: D SSDP ssdp.cpp:658 (ProcessNotify) SSDP::ProcessNotify ...#012DescURL=http://192.168.88.203:65530/root.sxml#012NTS    =ssdp:alive#012NT     =uuid:bc329e00-1dd8-11b2-8601-ccb255d51490#012USN    =uuid:bc329e00-1dd8-11b2-8601-ccb255d51490#012Cache  =max-age=60
Apr 16 16:31:16 frikkie mythbackend: mythbackend[6496]: E MythSocketThread(-1) mythsocket.cpp:827 (ReadStringListReal) MythSocket(1d62900:57): ReadStringList: Error, timed out after 30000 ms.
Apr 16 16:31:16 frikkie mythbackend: mythbackend[6496]: W MythSocketThread(-1) mainserver.cpp:7704 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x1d62900)
Apr 16 16:31:16 frikkie mythbackend: mythbackend[6496]: E CoreContext mythsocket.cpp:369 (SendReceiveStringList) MythSocket(1d62900:-1): No response.
Apr 16 16:31:16 frikkie mythbackend: mythbackend[6496]: E CoreContext mainserver.cpp:8040 (reconnectTimeout) MainServer: Failed to open master server socket, timeout
Apr 16 16:31:16 frikkie mythbackend: mythbackend[6496]: I MythSocketThread(57) mainserver.cpp:7693 (connectionClosed) Control sock(1d195f0) disconnected
Apr 16 16:31:17 frikkie mythbackend: mythbackend[6496]: N CoreContext mainserver.cpp:7991 (reconnectTimeout) MainServer: Connecting to master server: 192.168.0.2:6543
Apr 16 16:31:17 frikkie mythbackend: mythbackend[6496]: N CoreContext mainserver.cpp:8002 (reconnectTimeout) MainServer: Connected successfully


Post Reply