[mythtv-users] mythfrontend hangs when mythfilldatabase is running

Larry K lunchtimelarry at gmail.com
Tue Sep 25 01:24:42 UTC 2012


I am on mythtv 0.25.2 on EL6.  Lately, I've noticed that my frontend has
started to hang periodically and become unresponsive to either keyboard or
IR commands.   Today, I dug a bit deeper and found this in my frontend log:

<many deleted lines>
2012-09-23 17:54:12.867672 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-Alerts
2012-09-23 18:04:12.882817 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-Alerts
2012-09-23 18:09:11.037350 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-XML
2012-09-23 18:09:11.053412 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NDFD-6_day
2012-09-23 18:14:12.905605 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-Alerts
2012-09-23 18:24:11.052140 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-XML
2012-09-23 18:24:11.075766 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NDFD-6_day
2012-09-23 18:24:12.926657 I [19851/19851] CoreContext
weatherSource.cpp:424 (startUpdate) - Starting update of NWS-Alerts
2012-09-24 04:55:50.953133 I [19851/20084] Socket mythcorecontext.cpp:1103
(readyRead) - Received remote 'Clear Cache' request
2012-09-24 04:55:51.105931 I [19851/19981] DBLogger mythdbcon.cpp:237
(Reconnect) - MySQL reconnected successfully
<big gap here>
2012-09-24 19:17:51.207815 C [21248/21248] thread_unknown
mythcommandlineparser.cpp:2534 (ConfigureLogging) - mythfrontend version:
 [v0.25.2] www.mythtv.org
2012-09-24 19:17:51.207865 C [21248/21248] thread_unknown
mythcommandlineparser.cpp:2536 (ConfigureLogging) - Qt version: compile:
4.7.2, runtime: 4.7.2
2012-09-24 19:17:51.207870 N [21248/21248] thread_unknown
mythcommandlineparser.cpp:2538 (ConfigureLogging) - Enabled verbose msgs:
 general
2012-09-24 19:17:51.208048 N [21248/21248] thread_unknown logging.cpp:1176
(logStart) - Setting Log Level to LOG_INFO
2012-09-24 19:17:51.208132 I [21248/21248] thread_unknown logging.cpp:229
(FileLogger) - Added logging to the console
2012-09-24 19:17:51.208378 I [21248/21248] thread_unknown logging.cpp:238
(FileLogger) - Added logging to
/var/log/mythtv/mythfrontend.20120924191751.21248.log
2012-09-24 19:17:51.208392 I [21248/21248] thread_unknown logging.cpp:425
(DatabaseLogger) - Added database logging to table logging
2012-09-24 19:17:51.208540 N [21248/21248] thread_unknown logging.cpp:1215
(logStart) - Setting up SIGHUP handler
<more lines here>

Notice the big gap in the time stamps of the frontend log.  Only when I
restarted at 19:17 did the frontend become responsive again.  I think it
was hung all day today, from 04:55 all the way up to 19:17 when I restarted.

Since the problem happened in the wee hours of the morning, it occurred to
me to check the mythfilldatabase log.  It has this:

<many deleted lines>
2012-09-24 04:52:52.557164 I  Adjusting program database end times.
2012-09-24 04:52:58.021447 I      1 replacements made
2012-09-24 04:52:58.021464 I  Marking generic episodes.
2012-09-24 04:52:59.906780 I      Found 2837
2012-09-24 04:52:59.906942 I  Extending non-unique programids with multiple
parts.
2012-09-24 04:53:00.281210 I      Found 0
2012-09-24 04:53:00.281281 I  Marking repeats.
2012-09-24 04:53:02.756555 I      Found 9052
2012-09-24 04:53:02.756577 I  Unmarking new episode rebroadcast repeats.
2012-09-24 04:53:03.236025 I      Found 0
2012-09-24 04:53:07.532096 I  Marking episode first showings.
2012-09-24 04:54:30.198931 I      Found 29935
2012-09-24 04:54:30.198950 I  Marking episode last showings.
2012-09-24 04:55:48.983045 I      Found 29935
2012-09-24 04:55:49.371278 I  DataDirect: Grabbing next suggested grabbing
time
2012-09-24 04:55:50.851191 I  Suggested Time data: 612 bytes
2012-09-24 04:55:50.857193 I  DataDirect: BlockedTime is:
2012-09-24T04:55:50
2012-09-24 04:55:50.857726 I  DataDirect: NextSuggestedTime is:
2012-09-25T12:27:40
2012-09-24 04:55:50.861291 I
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2012-09-24 04:55:50.873260 I  MythCoreContext: Connecting to backend
server: 192.168.1.200:6543 (try 1 of 1)
2012-09-24 04:55:50.909066 I  Using protocol version 72
2012-09-24 04:55:50.923943 N  mythfilldatabase run complete.
Application asked to unregister timer 0x30000001 which is not registered in
this thread. Fix application.
2012-09-24 04:55:51.035081 I  DataDirect: Deleting temporary files


At 04:55 the mythfilldatabase task seems to have encountered an error that
coincides with the time my frontend probably went unresponsive.   The
second from last error message looks like it might be a clue:  Application
asked to unregister timer 0x30000001 which is not registered in this
thread. Fix application.

Any thoughts on what could be the problem?

Larry
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20120924/ea699e34/attachment.html>


More information about the mythtv-users mailing list