Author |
Message |
sparks
|
Posted: Mon May 20, 2019 1:08 pm |
|
Joined: Sun Nov 13, 2005 5:56 pm
Posts: 104
|
The topic sez it all. I just keep getting the same log entries repeated every 10 minutes or so. Code: 2019-05-20T14:30:00.076855-04:00 mythbackend[3127]: N Scheduler scheduler.cpp:3273 (HandleIdleShutdown) Blocking shutdown because of a connected client 2019-05-20T14:30:13.588539-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1780 (HandleAnnounce) MainServer: MainServer::ANN Monitor 2019-05-20T14:30:13.588579-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1785 (HandleAnnounce) MainServer: adding: linhes(55bba9b93bb0) as a client (events: 0) 2019-05-20T14:30:14.289459-04:00 mythbackend[3127]: I MythSocketThread(55) mainserver.cpp:7928 (connectionClosed) Monitor sock(55bba9b93bb0) 'linhes' disconnected 2019-05-20T14:35:14.047972-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1780 (HandleAnnounce) MainServer: MainServer::ANN Monitor 2019-05-20T14:35:14.048020-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1785 (HandleAnnounce) MainServer: adding: linhes(55bba9b93bb0) as a client (events: 0) 2019-05-20T14:35:14.648714-04:00 mythbackend[3127]: I MythSocketThread(55) mainserver.cpp:7928 (connectionClosed) Monitor sock(55bba9b93bb0) 'linhes' disconnected 2019-05-20T14:40:14.408193-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1780 (HandleAnnounce) MainServer: MainServer::ANN Monitor 2019-05-20T14:40:14.408242-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1785 (HandleAnnounce) MainServer: adding: linhes(55bba9b93bb0) as a client (events: 0) 2019-05-20T14:40:15.109135-04:00 mythbackend[3127]: I MythSocketThread(55) mainserver.cpp:7928 (connectionClosed) Monitor sock(55bba9b93bb0) 'linhes' disconnected 2019-05-20T14:40:53.942712-04:00 mythbackend[3127]: N Expire autoexpire.cpp:261 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 50.0 GB w/freq: 15 min 2019-05-20T14:42:58.850623-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1780 (HandleAnnounce) MainServer: MainServer::ANN Monitor 2019-05-20T14:42:58.850670-04:00 mythbackend[3127]: I ProcessRequest mainserver.cpp:1785 (HandleAnnounce) MainServer: adding: linhes(55bba9b93bb0) as a client (events: 0) 2019-05-20T14:42:58.950850-04:00 mythbackend[3127]: I MythSocketThread(56) mainserver.cpp:7928 (connectionClosed) Monitor sock(55bba9b93bb0) 'linhes' disconnected I have 63 GB free on the disk so I'm not sure what that entry is about. I see linhes is connecting and disconnecting but I am not sure where, how or why. I am running idle.py without the 'l' option. This is something that started in the last month or two. sparks
|
|
Top |
|
![](images/spacer.gif) |
knappster
|
Posted: Mon May 20, 2019 6:59 pm |
|
Joined: Thu Sep 27, 2007 5:44 pm
Posts: 580
|
Do you have any remote frontends? After upgrading a remote kodi box to 18, I started to have similar issues because it was blocking shutdown when idle, even when it wasn't supposed to. This is the message that makes me wonder: Code: Blocking shutdown because of a connected client Unfortunately I do not know of a way to interrogate which frontend is responsible, though.
|
|
Top |
|
![](images/spacer.gif) |
sparks
|
Posted: Mon May 20, 2019 7:26 pm |
|
Joined: Sun Nov 13, 2005 5:56 pm
Posts: 104
|
The only frontend client I have is a RPi box that is completely powered down, wall wart not even plugged in. I rarely use it. linhes is the name of my front/back end box so I figured it was something internal to my main box. Still stymied.
s
|
|
Top |
|
![](images/spacer.gif) |
knappster
|
Posted: Sun May 26, 2019 4:21 am |
|
Joined: Thu Sep 27, 2007 5:44 pm
Posts: 580
|
Sorry that I can't be of better help. I have had frustrating MythWelcome issues in the past and I find it difficult to troubleshoot, particularly with remote frontends in the mix. Since that is not an issue for you, can you provide more detail about your setup in hopes that it will resonate with somebody? Are you on 8.6.1? Are you manually exiting the frontend or are you letting the frontend go idle?
I believe that the added client and disconnected messages are normal because I see that all the time on my machine (which is also a hostname of linhes).
|
|
Top |
|
![](images/spacer.gif) |
sparks
|
Posted: Tue May 28, 2019 9:49 am |
|
Joined: Sun Nov 13, 2005 5:56 pm
Posts: 104
|
Thanks for the reply.
I am on the latest release of LinHES running on a an Intel motherboard with 8GB, H77 chipset, i7 cpu, a 220 GB SSD for LinHES, and a 1 GB HD for recordings. As far as usage goes, when I am done at night I manually backout of the frontend and let it sit with the mythwelcome screen. It's not unusal for backend jobs to be running at that time because I transcode all my recordings so I just expect it to shut down when it finishes. It used to until rececently (last month or two). When I check it the next day I find it still running, that it never shut down even though it sat idle for hours. I thought mythwelcome had a log but I haven't found it yet.
sparks
|
|
Top |
|
![](images/spacer.gif) |
sparks
|
Posted: Sun Jun 23, 2019 9:29 am |
|
Joined: Sun Nov 13, 2005 5:56 pm
Posts: 104
|
I was finally able to give this problem some attention again. I was able to get mythwelcome to produce a log that I set at DEBUG level. I get the same portion of log repeating. It shows mythshutdown returning a 0 status that I thought should be good. What ever picks up that status code isn't doing its job. I have no idea what the next link is. Any ideas? sparks Code: 2019-06-23 11:21:29.732906 C [30265/30265] thread_unknown mythcommandlineparser.cpp:2607 (ConfigureLogging) - mythshutdown version: fixes/30 [v30.0-34-g81081957a0] www.mythtv.org 2019-06-23 11:21:29.732938 C [30265/30265] thread_unknown mythcommandlineparser.cpp:2609 (ConfigureLogging) - Qt version: compile: 5.10.1, runtime: 5.10.1 2019-06-23 11:21:29.732940 N [30265/30265] thread_unknown mythcommandlineparser.cpp:2611 (ConfigureLogging) - Enabled verbose msgs: general 2019-06-23 11:21:29.733022 N [30265/30265] thread_unknown logging.cpp:734 (logStart) - Setting Log Level to LOG_DEBUG 2019-06-23 11:21:29.743403 I [30265/30268] Logger logging.cpp:296 (run) - Added logging to the console 2019-06-23 11:21:29.743808 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt handler 2019-06-23 11:21:29.743819 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated handler 2019-06-23 11:21:29.743834 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault handler 2019-06-23 11:21:29.743842 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Aborted handler 2019-06-23 11:21:29.743851 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error handler 2019-06-23 11:21:29.743857 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception handler 2019-06-23 11:21:29.743867 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction handler 2019-06-23 11:21:29.743875 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Real-time signal 0 handler 2019-06-23 11:21:29.743886 I [30265/30265] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Hangup handler 2019-06-23 11:21:29.743889 I [30265/30267] LogForward loggingserver.cpp:129 (FileLogger) - Added logging to /var/log/mythwelcome/mythshutdown.20190623152129.30265.log 2019-06-23 11:21:29.744007 N [30265/30265] thread_unknown mythdirs.cpp:203 (InitializeMythDirs) - Using runtime prefix = /usr 2019-06-23 11:21:29.744011 N [30265/30265] thread_unknown mythdirs.cpp:205 (InitializeMythDirs) - Using configuration directory = /home/mythtv/.mythtv 2019-06-23 11:21:29.744016 D [30265/30265] thread_unknown mythdirs.cpp:207 (InitializeMythDirs) - appbindir = /usr/bin/ 2019-06-23 11:21:29.744019 D [30265/30265] thread_unknown mythdirs.cpp:208 (InitializeMythDirs) - sharedir = /usr/share/mythtv/ 2019-06-23 11:21:29.744023 D [30265/30265] thread_unknown mythdirs.cpp:209 (InitializeMythDirs) - libdir = /usr/lib/mythtv/ 2019-06-23 11:21:29.744025 D [30265/30265] thread_unknown mythdirs.cpp:210 (InitializeMythDirs) - themedir = /usr/share/mythtv/themes/ 2019-06-23 11:21:29.744029 D [30265/30265] thread_unknown mythdirs.cpp:211 (InitializeMythDirs) - pluginsdir = /usr/lib/mythtv/plugins/ 2019-06-23 11:21:29.744032 D [30265/30265] thread_unknown mythdirs.cpp:212 (InitializeMythDirs) - translationsdir = /usr/share/mythtv/i18n/ 2019-06-23 11:21:29.744038 D [30265/30265] thread_unknown mythdirs.cpp:213 (InitializeMythDirs) - filtersdir = /usr/lib/mythtv/filters/ 2019-06-23 11:21:29.744042 D [30265/30265] thread_unknown mythdirs.cpp:214 (InitializeMythDirs) - cachedir = /home/mythtv/.mythtv/cache 2019-06-23 11:21:29.744047 D [30265/30265] thread_unknown mythdirs.cpp:215 (InitializeMythDirs) - remotecachedir = /home/mythtv/.mythtv/cache/remotecache 2019-06-23 11:21:29.744056 D [30265/30265] thread_unknown mythdirs.cpp:216 (InitializeMythDirs) - themebasecachedir = /home/mythtv/.mythtv/cache/themecache 2019-06-23 11:21:29.744058 D [30265/30265] thread_unknown mythdirs.cpp:217 (InitializeMythDirs) - thumbnaildir = /home/mythtv/.mythtv/cache/thumbnails 2019-06-23 11:21:29.744132 I [30265/30265] CoreContext mythcorecontext.cpp:272 (Init) - Assumed character encoding: en_US.UTF-8 2019-06-23 11:21:29.744450 I [30265/30265] CoreContext mythcontext.cpp:641 (LoadDatabaseSettings) - Empty LocalHostName. This is typical. 2019-06-23 11:21:29.744459 I [30265/30265] CoreContext mythcontext.cpp:650 (LoadDatabaseSettings) - Using a profile name of: 'linhes' (Usually the same as this host's name.) 2019-06-23 11:21:29.744542 I [30265/30265] CoreContext mythcontext.cpp:900 (TestDBconnection) - Start up testing connections. DB 127.0.0.1, BE , attempt 0, status dbAwake, Delay: 2000 2019-06-23 11:21:29.744552 D [30265/30265] CoreContext portchecker.cpp:81 (checkPort) - PortChecker::checkPort(): host 127.0.0.1 port 3306 timeLimit 1000 linkLocalOnly 0 2019-06-23 11:21:30.245133 D [30265/30265] CoreContext portchecker.cpp:197 (checkPort) - PortChecker::checkPort(): socket state 3 2019-06-23 11:21:30.255251 D [30265/30265] CoreContext portchecker.cpp:81 (checkPort) - PortChecker::checkPort(): host 127.0.0.1 port 3306 timeLimit 30000 linkLocalOnly 1 2019-06-23 11:21:30.261061 D [30265/30265] CoreContext mythcontext.cpp:525 (FindDatabase) - FindDatabase() - Success! 2019-06-23 11:21:30.261138 D [30265/30265] CoreContext portchecker.cpp:81 (checkPort) - PortChecker::checkPort(): host 127.0.0.1 port 3306 timeLimit 30000 linkLocalOnly 1 2019-06-23 11:21:30.263158 N [30265/30265] CoreContext mythcorecontext.cpp:1864 (InitLocale) - Setting QT default locale to en_US 2019-06-23 11:21:30.263174 I [30265/30265] CoreContext mythcorecontext.cpp:1897 (SaveLocaleDefaults) - Current locale en_US 2019-06-23 11:21:30.263234 N [30265/30265] CoreContext mythlocale.cpp:123 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml 2019-06-23 11:21:30.266494 I [30265/30265] CoreContext main.cpp:240 (getStatus) - Mythshutdown: --status 2019-06-23 11:21:30.266990 I [30265/30295] SystemSignalManager mythsystemunix.cpp:509 (run) - Starting process signal handler 2019-06-23 11:21:30.266995 I [30265/30294] SystemManager mythsystemunix.cpp:276 (run) - Starting process manager 2019-06-23 11:21:30.268816 I [30265/30296] SystemIOHandlerR mythsystemunix.cpp:92 (run) - Starting IO manager (read) 2019-06-23 11:21:30.268847 I [30265/30297] SystemIOHandlerW mythsystemunix.cpp:92 (run) - Starting IO manager (write) 2019-06-23 11:21:30.467625 I [30265/30265] CoreContext main.cpp:369 (getStatus) - Mythshutdown: --status returned: 0
|
|
Top |
|
![](images/spacer.gif) |
knappster
|
Posted: Thu Jul 04, 2019 5:42 am |
|
Joined: Thu Sep 27, 2007 5:44 pm
Posts: 580
|
sparks, is that a mythwelcome or mythshutdown log? My mythshutdown ends with the line: Code: 2019-07-03T22:12:22.620160-05:00 mythshutdown[32365]: I CoreContext main.cpp:369 (getStatus) Mythshutdown: --status returned: 0 which appears to match yours. Is it possible that your shutdown command got corrupted somehow?
|
|
Top |
|
![](images/spacer.gif) |
sparks
|
Posted: Fri Oct 18, 2019 10:15 am |
|
Joined: Sun Nov 13, 2005 5:56 pm
Posts: 104
|
Sorry for bumping this topic but I thought the responders deserved a response to the final solution. It's quite unsatisfactory. It just started working on it's own. ![Confused :?](./images/smilies/icon_confused.gif) So next time this happens, I still won't know what to do but wait. Apologies for waiting so long to respond. s
|
|
Top |
|
![](images/spacer.gif) |
mattbatt
|
Posted: Sun Oct 20, 2019 7:42 am |
|
Joined: Tue Aug 15, 2006 11:14 am
Posts: 1343
Location:
Orlando FL
|
It's unnerving when things just "fix themselves".
_________________ My System
|
|
Top |
|
![](images/spacer.gif) |