0.25 Backend stops and restarts every minute or so with no error

  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: SELECT data, created, headers, expire, serialized FROM cache_filter WHERE cid = '1:5d6243d86c70a2bd8349d021c4bdff0f' in /var/www/mythdora/html/includes/cache.inc on line 26.
  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: UPDATE cache_filter SET data = '<p>I have been using Mythdora without issue for a few years now and upgraded to each new version. I finally took the plunge on 0.25 and everything appears to work except the backend cycles itself every minute or so, without an error apparently causing it (though the Bonjour service is not registering correctly). A sample log file is below. Every time it cycles it produces an identical log file, but I cannot see what may be causing it to shutdown. Any ideas? While it is up, all clients, local and remote function correctly.</p>\n<p>2012-05-14 06:43:06.293102 W [2818/2818] thread_unknown mythcommandlineparser.cpp:2613 (setUser) - Already running as \'root\'<br />\n2012-05-14 06:43:06.294535 C [2818/2818] thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) - mythbackend version: fixes/0.25 [0.25-3.md12 (1a671d0)] <a href=\"http://www.mythtv.org\">www.mythtv.org</a><br />\n2012-05-14 06:43:06.294576 N [2818/2818] thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) - Enabled verbose msgs: general<br />\n2012-05-14 06:43:06.295724 N [2818/2818] thread_unknown logging.cpp:1176 (logStart) - Setting Log Level to LOG_INFO<br />\n2012-05-14 06:43:06.296170 I [2818/2818] thread_unknown logging.cpp:229 (FileLogger) - Added logging to the console<br />\n2012-05-14 06:43:06.307181 I [2818/2818] thread_unknown logging.cpp:238 (FileLogger) - Added logging to /var/log/mythtv/mythbackend.20120514064306.2818.log<br />\n2012-05-14 06:43:06.307229 I [2818/2818] thread_unknown logging.cpp:425 (DatabaseLogger) - Added database logging to table logging<br />\n2012-05-14 06:43:06.307517 N [2818/2818] thread_unknown logging.cpp:1215 (logStart) - Setting up SIGHUP handler<br />\n2012-05-14 06:43:06.307953 N [2818/2818] thread_unknown mythdirs.cpp:51 (InitializeMythDirs) - Using runtime prefix = /usr<br />\n2012-05-14 06:43:06.307992 N [2818/2818] thread_unknown mythdirs.cpp:57 (InitializeMythDirs) - Read conf dir = /home/mythtv<br />\n2012-05-14 06:43:06.308158 N [2818/2818] thread_unknown mythdirs.cpp:64 (InitializeMythDirs) - Using configuration directory = /home/mythtv<br />\n2012-05-14 06:43:06.308551 I [2818/2818] CoreContext mythcorecontext.cpp:227 (Init) - Assumed character encoding: en_US.UTF-8<br />\n2012-05-14 06:43:06.310439 N [2818/2818] CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) - Empty LocalHostName.<br />\n2012-05-14 06:43:06.310473 I [2818/2818] CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) - Using localhost value of mythictree.theforest.local<br />\n2012-05-14 06:43:06.339454 N [2818/2818] CoreContext mythcorecontext.cpp:1270 (InitLocale) - Setting QT default locale to EN_US<br />\n2012-05-14 06:43:06.339494 I [2818/2818] CoreContext mythcorecontext.cpp:1303 (SaveLocaleDefaults) - Current locale EN_US<br />\n2012-05-14 06:43:06.339616 N [2818/2818] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml<br />\n2012-05-14 06:43:06.363413 I [2818/2818] CoreContext schemawizard.cpp:117 (Compare) - Current MythTV Schema Version (DBSchemaVer): 1299<br />\n2012-05-14 06:43:06.364635 I [2818/2818] CoreContext mythtranslation.cpp:66 (load) - Loading en_us translation for module mythfrontend<br />\n2012-05-14 06:43:06.366733 N [2818/2818] CoreContext main_helpers.cpp:556 (run_backend) - MythBackend: Starting up as the master server.<br />\n2012-05-14 06:43:08.080190 I [2818/2818] CoreContext programinfo.cpp:2052 (CheckProgramIDAuthorities) - Found 1 distinct programid authorities<br />\n2012-05-14 06:43:08.080784 I [2818/2834] Scheduler mythdbcon.cpp:422 (getStaticCon) - New static DB connectionSchedCon<br />\n2012-05-14 06:43:08.094459 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6544<br />\n2012-05-14 06:43:08.094603 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 10.0.1.90:6544<br />\n2012-05-14 06:43:08.096630 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6544<br />\n2012-05-14 06:43:09.194843 E [2818/2818] CoreContext bonjourregister.cpp:53 (Register) - Bonjour: Error: -65537<br />\n2012-05-14 06:43:09.194875 E [2818/2818] CoreContext bonjourregister.cpp:68 (Register) - Bonjour: Failed to register service.<br />\n2012-05-14 06:43:09.194896 I [2818/2818] CoreContext main_helpers.cpp:626 (run_backend) - Main::Registering HttpStatus Extension<br />\n2012-05-14 06:43:09.201175 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6543<br />\n2012-05-14 06:43:09.201291 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 10.0.1.90:6543<br />\n2012-05-14 06:43:09.203170 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6543<br />\n2012-05-14 06:43:09.208823 N [2818/2818] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min<br />\n2012-05-14 06:43:11.089989 I [2818/2834] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.<br />\n2012-05-14 06:43:11.157571 I [2818/2834] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 0 items in 0.1 = 0.01 match + 0.06 place<br />\n2012-05-14 06:43:11.165977 I [2818/2834] Scheduler scheduler.cpp:2136 (HandleRunSchedulerStartup) - Scheduler: Seem to be woken up by USER<br />\n2012-05-14 06:43:18.090139 I [2818/2836] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread</p>\n<p>Thank you for your assistance,</p>\n<p>Nathan</p>\n', created = 1369034149, expire = 1369120549, headers = '', serialized = 0 WHERE cid = '1:5d6243d86c70a2bd8349d021c4bdff0f' in /var/www/mythdora/html/includes/cache.inc on line 109.
  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: SELECT data, created, headers, expire, serialized FROM cache_filter WHERE cid = '1:1fe1b5074462b2768d0fce6f3f98cb52' in /var/www/mythdora/html/includes/cache.inc on line 26.
  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: UPDATE cache_filter SET data = '<p><a href=\"http://mythdora.com/?q=node/5924#comment-22930\">http://mythdora.com/?q=node/5924#comment-22930</a></p>\n', created = 1369034149, expire = 1369120549, headers = '', serialized = 0 WHERE cid = '1:1fe1b5074462b2768d0fce6f3f98cb52' in /var/www/mythdora/html/includes/cache.inc on line 109.
  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: SELECT data, created, headers, expire, serialized FROM cache_filter WHERE cid = '1:48489a91fda3d1571586f05f0734b983' in /var/www/mythdora/html/includes/cache.inc on line 26.
  • user warning: Table './drupal_gding/cache_filter' is marked as crashed and should be repaired query: UPDATE cache_filter SET data = '<p>I saw that thread in my searches earlier, but in my state of mind without sleep I missed the context of the changes outlined. I saw this time he was experiencing the same thing and I was able to fix it straight away. Thank you.</p>\n', created = 1369034149, expire = 1369120549, headers = '', serialized = 0 WHERE cid = '1:48489a91fda3d1571586f05f0734b983' in /var/www/mythdora/html/includes/cache.inc on line 109.

I have been using Mythdora without issue for a few years now and upgraded to each new version. I finally took the plunge on 0.25 and everything appears to work except the backend cycles itself every minute or so, without an error apparently causing it (though the Bonjour service is not registering correctly). A sample log file is below. Every time it cycles it produces an identical log file, but I cannot see what may be causing it to shutdown. Any ideas? While it is up, all clients, local and remote function correctly.

2012-05-14 06:43:06.293102 W [2818/2818] thread_unknown mythcommandlineparser.cpp:2613 (setUser) - Already running as 'root'
2012-05-14 06:43:06.294535 C [2818/2818] thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) - mythbackend version: fixes/0.25 [0.25-3.md12 (1a671d0)] www.mythtv.org
2012-05-14 06:43:06.294576 N [2818/2818] thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) - Enabled verbose msgs: general
2012-05-14 06:43:06.295724 N [2818/2818] thread_unknown logging.cpp:1176 (logStart) - Setting Log Level to LOG_INFO
2012-05-14 06:43:06.296170 I [2818/2818] thread_unknown logging.cpp:229 (FileLogger) - Added logging to the console
2012-05-14 06:43:06.307181 I [2818/2818] thread_unknown logging.cpp:238 (FileLogger) - Added logging to /var/log/mythtv/mythbackend.20120514064306.2818.log
2012-05-14 06:43:06.307229 I [2818/2818] thread_unknown logging.cpp:425 (DatabaseLogger) - Added database logging to table logging
2012-05-14 06:43:06.307517 N [2818/2818] thread_unknown logging.cpp:1215 (logStart) - Setting up SIGHUP handler
2012-05-14 06:43:06.307953 N [2818/2818] thread_unknown mythdirs.cpp:51 (InitializeMythDirs) - Using runtime prefix = /usr
2012-05-14 06:43:06.307992 N [2818/2818] thread_unknown mythdirs.cpp:57 (InitializeMythDirs) - Read conf dir = /home/mythtv
2012-05-14 06:43:06.308158 N [2818/2818] thread_unknown mythdirs.cpp:64 (InitializeMythDirs) - Using configuration directory = /home/mythtv
2012-05-14 06:43:06.308551 I [2818/2818] CoreContext mythcorecontext.cpp:227 (Init) - Assumed character encoding: en_US.UTF-8
2012-05-14 06:43:06.310439 N [2818/2818] CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) - Empty LocalHostName.
2012-05-14 06:43:06.310473 I [2818/2818] CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) - Using localhost value of mythictree.theforest.local
2012-05-14 06:43:06.339454 N [2818/2818] CoreContext mythcorecontext.cpp:1270 (InitLocale) - Setting QT default locale to EN_US
2012-05-14 06:43:06.339494 I [2818/2818] CoreContext mythcorecontext.cpp:1303 (SaveLocaleDefaults) - Current locale EN_US
2012-05-14 06:43:06.339616 N [2818/2818] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2012-05-14 06:43:06.363413 I [2818/2818] CoreContext schemawizard.cpp:117 (Compare) - Current MythTV Schema Version (DBSchemaVer): 1299
2012-05-14 06:43:06.364635 I [2818/2818] CoreContext mythtranslation.cpp:66 (load) - Loading en_us translation for module mythfrontend
2012-05-14 06:43:06.366733 N [2818/2818] CoreContext main_helpers.cpp:556 (run_backend) - MythBackend: Starting up as the master server.
2012-05-14 06:43:08.080190 I [2818/2818] CoreContext programinfo.cpp:2052 (CheckProgramIDAuthorities) - Found 1 distinct programid authorities
2012-05-14 06:43:08.080784 I [2818/2834] Scheduler mythdbcon.cpp:422 (getStaticCon) - New static DB connectionSchedCon
2012-05-14 06:43:08.094459 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6544
2012-05-14 06:43:08.094603 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 10.0.1.90:6544
2012-05-14 06:43:08.096630 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6544
2012-05-14 06:43:09.194843 E [2818/2818] CoreContext bonjourregister.cpp:53 (Register) - Bonjour: Error: -65537
2012-05-14 06:43:09.194875 E [2818/2818] CoreContext bonjourregister.cpp:68 (Register) - Bonjour: Failed to register service.
2012-05-14 06:43:09.194896 I [2818/2818] CoreContext main_helpers.cpp:626 (run_backend) - Main::Registering HttpStatus Extension
2012-05-14 06:43:09.201175 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6543
2012-05-14 06:43:09.201291 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 10.0.1.90:6543
2012-05-14 06:43:09.203170 I [2818/2818] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6543
2012-05-14 06:43:09.208823 N [2818/2818] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
2012-05-14 06:43:11.089989 I [2818/2834] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-05-14 06:43:11.157571 I [2818/2834] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 0 items in 0.1 = 0.01 match + 0.06 place
2012-05-14 06:43:11.165977 I [2818/2834] Scheduler scheduler.cpp:2136 (HandleRunSchedulerStartup) - Scheduler: Seem to be woken up by USER
2012-05-14 06:43:18.090139 I [2818/2836] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread

Thank you for your assistance,

Nathan

http://mythdora.com/?q=node/5

Fixed - Thank you!

I saw that thread in my searches earlier, but in my state of mind without sleep I missed the context of the changes outlined. I saw this time he was experiencing the same thing and I was able to fix it straight away. Thank you.