[mythtv-users] mythlogserver multiple copies high cpu

David Farmer remrafevad at gmail.com
Thu Mar 7 21:09:58 UTC 2013


On 07/03/13 20:03, David Farmer wrote:
>
> Ok, so it took a while but I have a recurrence of the problem. A quick 
> description of the events before and after I noticed it.
>
> 1. I logged into the HTPC via ssh to see how many mythlogserver 
> processes were running, only 1 and cpu usage was less than 1%. I then 
> logged out.
> 2. I opened mythweb from the same pc as I did the ssh. I cancelled a 
> few upcoming recordings that were scheduled via a custom search rule. 
> Closed Mythweb.
> 3. Ate dinner
> 4. I sat in front of the HTPC and checked how many mythlogservers were 
> open, 7 or 8 (forgot to count!) of them and cpu usage was maximum.
>
> The log file is huge though, because all this time Shepherd (my 
> Australian program listings grabber) was running as a cron job. An 
> immediate issues I've noted in the log is
>
> "Mar 07 18:51:20 david-htpc systemd-coredump[2706]: Process 2672 
> (mythpreviewgen) dumped core."
>
> Not sure which other messages are important, how should the log be 
> uploaded?
>
> If you grep the logs, (say for "bin/mythlogserver"), you ought to
> find several instances of mythlogserver being started while you were
> at dinner. Maybe they are all in your mythbackend log, but they
> could also be in the logs of any other program that ran in that
> period: mythcommflag (?), mythtranscode, mythpreviewgen, etc.
>
> Craig
>
If I do a "journalctl -b" to get the entire log since the last boot, 
there is no instance of mythlogserver in there. I have to go to 
/var/log/mythtv/mythbackend.20130307033801.953.log to find the following 
lines.

2013-03-07 18:51:20.520888 E [953/2173] HttpServer76 
previewgenerator.cpp:255 (Run) - Preview: Encountered problems running 
'/usr/bin/mythpreviewgen --size 100x75 --chanid 1030 --starttime 
20130306142201 --outfile 
"/home/david/MythTV/Recordings/mythRecordings/Paradise_Now_06.03.2013.mkv.-1.100x75.png" 
--verbose general --logpath /var/log/mythtv --loglevel debug --quiet' (140)

Most of the log file is entries like the following - there are thousands 
of them!

2013-03-07 18:51:38.361070 E [953/2473] ProcessRequest 
programinfo.cpp:2284 (GetPlaybackURL) - 
ProgramInfo(The_Lord_of_the_Rings_The_Return_of_the_King_21.12.2012.mkv): GetPlaybackURL: 
'The_Lord_of_the_Rings_The_Return_of_the_King_21.12.2012.mkv' should be 
local, but it can not be found.
The recordings that are being referred to are the same set over and over 
again, and have all been deleted a while ago.

I'm also getting the following many times throughout the log.
2013-03-07 18:51:39.822541 I [953/2065] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Playback
2013-03-07 18:51:39.822552 I [953/2065] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 0)
2013-03-07 18:51:39.830338 I [953/2473] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Playback
2013-03-07 18:51:39.830345 I [953/2473] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 0)
2013-03-07 18:51:39.859228 I [953/2473] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Monitor
2013-03-07 18:51:39.859238 I [953/2473] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 1)
2013-03-07 18:51:39.869480 I [953/2065] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Monitor
2013-03-07 18:51:39.869491 I [953/2065] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 1)
2013-03-07 18:51:39.890984 I [953/2065] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Playback
2013-03-07 18:51:39.890992 I [953/2065] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 0)

and I also get the following.

2013-03-07 18:56:56.922885 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 18:59:33.034137 N [953/1008] Expire autoexpire.cpp:264 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 1.0 GB 
w/freq: 15 min
2013-03-07 19:01:59.924596 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 19:07:03.926404 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 19:08:25.638427 D [953/1013] TaskQueue 
mmulticastsocketdevice.cpp:86 (~MMulticastSocketDevice) - 
MMulticastSocketDevice(239.255.255.250:76): setsockopt - IP_DROP_MEMBERSHIP
                         eno: Invalid argument (22)
2013-03-07 19:12:09.928122 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 19:14:33.127221 N [953/1008] Expire autoexpire.cpp:264 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 1.0 GB 
w/freq: 15 min
2013-03-07 19:17:14.931691 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 19:22:15.933466 I [953/1007] HouseKeeping housekeeper.cpp:221 
(RunHouseKeeping) - Running housekeeping thread
2013-03-07 19:22:24.202414 I [953/3271] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Playback
2013-03-07 19:22:24.202423 I [953/3271] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 0)
2013-03-07 19:22:24.202941 I [953/3270] ProcessRequest 
mainserver.cpp:1383 (HandleAnnounce) - MainServer::ANN Monitor
2013-03-07 19:22:24.202950 I [953/3270] ProcessRequest 
mainserver.cpp:1385 (HandleAnnounce) - adding: david-htpc as a client 
(events: 1)
2013-03-07 19:22:24.204756 I [953/1005] Scheduler scheduler.cpp:2127 
(HandleReschedule) - Reschedule requested for MATCH 0 0 0 - MythFillDatabase
2013-03-07 19:22:24.229373 W [953/3270] ProcessRequest 
mainserver.cpp:5827 (connectionClosed) - MainServer: Unknown socket 
closing MythSocket(0x16cfa40)
2013-03-07 19:22:24.238686 E [953/3270] ProcessRequest 
mythsocket.cpp:344 (writeStringList) - MythSocket(16cfa40:-1): 
writeStringList: Error, socket went unconnected.
                         We wrote 0 of 10 bytes with 1 errors
                         starts with: 2       OK
2013-03-07 19:22:24.599929 I [953/1005] Scheduler scheduler.cpp:2240 
(HandleReschedule) - Scheduled 43 items in 0.4 = 0.20 match + 0.01 check 
+ 0.15 place

Is there anything in these that is really troubling?
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20130308/6ba32608/attachment.html>


More information about the mythtv-users mailing list