[mythtv-users] Mythfilldatabase not adding new data
David Kramer
david at thekramers.net
Mon Sep 24 04:39:06 UTC 2012
Mythfilldatabase is running daily, but not adding data for new days.
Someone suggest I run it manually with -dd-grab-all and that worked, but
it's still not filling automatically.
mythtv backend status from mythweb says:
Last mythfilldatabase run started on 2012-09-23 14:21:26 and ended
on 2012-09-23 14:23:16. mythfilldatabase ran, but did not insert any new
data into the Guide for 1 of 1 sources. This can indicate a potential
grabber failure.
Suggested next mythfilldatabase run: 2012-09-24 14:52.
There's guide data until 2012-10-05 22:00 (11 days).
Suggested next mythfilldatabase run: 2012-09-20 01:40.
There's guide data until 2012-09-30 07:00 (11 days).
DataDirect Status: Your subscription expires on Wed Jun 19 2013
10:26 PM"
What could be causing this? Below are the logs from the last
mythfilldatabase run:
david at stalker:/var/log$ grep mythfilldatabase syslog
Sep 23 14:14:24 stalker mythbackend[25287]: I HouseKeeping
housekeeper.cpp:299 (RunHouseKeeping) Running mythfilldatabase
Sep 23 14:14:25 stalker mythfilldatabase[31743]: C thread_unknown
mythcommandlineparser.cpp:2534 (ConfigureLogging) mythfilldatabase
version: fixes/0.25 [v0.25.2-15-g46cab93] www.mythtv.org
Sep 23 14:14:25 stalker mythfilldatabase[31743]: C thread_unknown
mythcommandlineparser.cpp:2536 (ConfigureLogging) Qt version: compile:
4.8.1, runtime: 4.8.1
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N thread_unknown
mythcommandlineparser.cpp:2538 (ConfigureLogging) Enabled verbose msgs:
general
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N thread_unknown
logging.cpp:1176 (logStart) Setting Log Level to LOG_INFO
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I thread_unknown
logging.cpp:229 (FileLogger) Added logging to the console
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I thread_unknown
logging.cpp:369 (SyslogLogger) Added syslogging to facility local7
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I thread_unknown
logging.cpp:425 (DatabaseLogger) Added database logging to table logging
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N thread_unknown
logging.cpp:1215 (logStart) Setting up SIGHUP handler
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N thread_unknown
mythdirs.cpp:51 (InitializeMythDirs) Using runtime prefix = /usr
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N thread_unknown
mythdirs.cpp:64 (InitializeMythDirs) Using configuration directory =
/home/mythtv/.mythtv
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I CoreContext
mythcorecontext.cpp:227 (Init) Assumed character encoding: en_US.UTF-8
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N CoreContext
mythcontext.cpp:477 (LoadDatabaseSettings) Empty LocalHostName.
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I CoreContext
mythcontext.cpp:481 (LoadDatabaseSettings) Using localhost value of stalker
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N CoreContext
mythcorecontext.cpp:1270 (InitLocale) Setting QT default locale to en_US
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I CoreContext
mythcorecontext.cpp:1303 (SaveLocaleDefaults) Current locale en_US
Sep 23 14:14:25 stalker mythfilldatabase[31743]: N CoreContext
mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from
/usr/share/mythtv//locales/en_us.xml
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I CoreContext
mythtranslation.cpp:66 (load) Loading en_us translation for module
mythfrontend
Sep 23 14:14:25 stalker mythfilldatabase[31743]: I CoreContext
schemawizard.cpp:117 (Compare) Current MythTV Schema Version
(DBSchemaVer): 1299
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:600 (Run) Updating source #1 (cablebox) with grabber
schedulesdirect1
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:615 (Run) Found 234 channels for source 1 which use grabber
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 0, date: Sun Sep 23 2012
Sep 23 14:14:32 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Sun Sep 23 2012, skipping
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 1, date: Mon Sep 24 2012
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:773 (Run) Data Refresh always needed for tomorrow
Sep 23 14:14:32 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:922 (Run) Refreshing data for Mon Sep 24 2012
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
mythdbcon.cpp:422 (getStaticCon) New static DB connectionDataDirectCon
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:231 (GrabDDData) Retrieving datadirect data.
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:248 (GrabDDData) Grabbing data for Sun Sep 23 2012 offset 1
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:251 (GrabDDData) From Mon Sep 24 04:00:00 2012 to Tue Sep
25 04:00:00 2012 (UTC)
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1158 (GrabData) DataDirect: Grabbing listing data
Sep 23 14:14:32 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1021 (DDPost) Downloading DataDirect feed
Sep 23 14:14:48 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1033 (DDPost) Downloaded 482975 bytes
Sep 23 14:14:48 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1035 (DDPost) Uncompressing DataDirect feed
Sep 23 14:14:48 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1040 (DDPost) Uncompressed to 4278085 bytes
Sep 23 14:14:49 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:468 (characters) DataDirect: Your subscription expires on
Wed Jun 19 2013 10:26 PM
Sep 23 14:14:49 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:2324 (set_lineup_type) DataDirect: sourceid 1 has lineup
type: CableDigital
Sep 23 14:16:17 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:272 (GrabDDData) Grab complete. Actual data from Wed Sep
19 00:00:00 2012 to Fri Oct 5 23:59:59 2012 (UTC)
Sep 23 14:16:17 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:276 (GrabDDData) Main temp tables populated.
Sep 23 14:16:17 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:279 (GrabDDData) Updating MythTV channels.
Sep 23 14:16:17 stalker mythfilldatabase[31743]: I CoreContext
icondata.cpp:164 (UpdateSourceIcons) IconData: Updating icons for
sourceid: 1
Sep 23 14:16:17 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:281 (GrabDDData) Channels updated.
Sep 23 14:16:22 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:309 (GrabDDData) Clearing data for source.
Sep 23 14:16:22 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:315 (GrabDDData) Clearing from Tue Sep 18 20:00:00 2012 to
Fri Oct 5 19:59:59 2012 (localtime)
Sep 23 14:17:09 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:317 (GrabDDData) Data for source cleared.
Sep 23 14:17:09 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:319 (GrabDDData) Updating programs.
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:321 (GrabDDData) Program table update complete.
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 2, date: Tue Sep 25 2012
Sep 23 14:18:05 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Tue Sep 25 2012, skipping
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 3, date: Wed Sep 26 2012
Sep 23 14:18:05 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Wed Sep 26 2012, skipping
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 4, date: Thu Sep 27 2012
Sep 23 14:18:05 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Thu Sep 27 2012, skipping
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 5, date: Fri Sep 28 2012
Sep 23 14:18:05 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Fri Sep 28 2012, skipping
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 6, date: Sat Sep 29 2012
Sep 23 14:18:05 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Sat Sep 29 2012, skipping
Sep 23 14:18:05 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 7, date: Sun Sep 30 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Sun Sep 30 2012, skipping
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 8, date: Mon Oct 1 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Mon Oct 1 2012, skipping
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 9, date: Tue Oct 2 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Tue Oct 2 2012, skipping
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 10, date: Wed Oct 3 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Wed Oct 3 2012, skipping
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 11, date: Thu Oct 4 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:944 (Run) Data is already present for Thu Oct 4 2012, skipping
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 12, date: Fri Oct 5 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:858 (Run) Data refresh needed because only 0 out of 234
channels have at least one program listed for day @ offset 12 from 8PM -
midnight. Previous day had 228 channels with data in that time period.
Sep 23 14:18:06 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:922 (Run) Refreshing data for Fri Oct 5 2012
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:231 (GrabDDData) Retrieving datadirect data.
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:248 (GrabDDData) Grabbing data for Sun Sep 23 2012 offset 12
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:251 (GrabDDData) From Fri Oct 5 04:00:00 2012 to Sat Oct 6
04:00:00 2012 (UTC)
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1158 (GrabData) DataDirect: Grabbing listing data
Sep 23 14:18:06 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1021 (DDPost) Downloading DataDirect feed
Sep 23 14:18:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1033 (DDPost) Downloaded 436887 bytes
Sep 23 14:18:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1035 (DDPost) Uncompressing DataDirect feed
Sep 23 14:18:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1040 (DDPost) Uncompressed to 3876132 bytes
Sep 23 14:18:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:468 (characters) DataDirect: Your subscription expires on
Wed Jun 19 2013 10:26 PM
Sep 23 14:19:30 stalker mythbackend[25287]: I HouseKeeping
housekeeper.cpp:244 (RunHouseKeeping) mythfilldatabase still running,
skipping checks.
Sep 23 14:19:55 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:272 (GrabDDData) Grab complete. Actual data from Wed Sep
19 00:00:00 2012 to Fri Oct 5 23:59:59 2012 (UTC)
Sep 23 14:19:55 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:276 (GrabDDData) Main temp tables populated.
Sep 23 14:20:00 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:309 (GrabDDData) Clearing data for source.
Sep 23 14:20:00 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:315 (GrabDDData) Clearing from Tue Sep 18 20:00:00 2012 to
Fri Oct 5 19:59:59 2012 (localtime)
Sep 23 14:20:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:317 (GrabDDData) Data for source cleared.
Sep 23 14:20:32 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:319 (GrabDDData) Updating programs.
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:321 (GrabDDData) Program table update complete.
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:764 (Run) Checking day @ offset 13, date: Sat Oct 6 2012
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:866 (Run) Data refresh needed because no data exists for
day @ offset 13 from 8PM - midnight.
Sep 23 14:21:26 stalker mythfilldatabase[31743]: N CoreContext
filldata.cpp:922 (Run) Refreshing data for Sat Oct 6 2012
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:231 (GrabDDData) Retrieving datadirect data.
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:248 (GrabDDData) Grabbing data for Sun Sep 23 2012 offset 13
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:251 (GrabDDData) From Sat Oct 6 04:00:00 2012 to Sun Oct 7
04:00:00 2012 (UTC)
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1158 (GrabData) DataDirect: Grabbing listing data
Sep 23 14:21:26 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1021 (DDPost) Downloading DataDirect feed
Sep 23 14:21:47 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1033 (DDPost) Downloaded 398607 bytes
Sep 23 14:21:47 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1035 (DDPost) Uncompressing DataDirect feed
Sep 23 14:21:47 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1040 (DDPost) Uncompressed to 3477680 bytes
Sep 23 14:21:47 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:468 (characters) DataDirect: Your subscription expires on
Wed Jun 19 2013 10:26 PM
Sep 23 14:23:16 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:272 (GrabDDData) Grab complete. Actual data from Wed Sep
19 00:00:00 2012 to Fri Oct 5 23:59:59 2012 (UTC)
Sep 23 14:23:16 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:276 (GrabDDData) Main temp tables populated.
Sep 23 14:23:21 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:309 (GrabDDData) Clearing data for source.
Sep 23 14:23:21 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:315 (GrabDDData) Clearing from Tue Sep 18 20:00:00 2012 to
Fri Oct 5 19:59:59 2012 (localtime)
Sep 23 14:23:54 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:317 (GrabDDData) Data for source cleared.
Sep 23 14:23:54 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:319 (GrabDDData) Updating programs.
Sep 23 14:24:32 stalker mythbackend[25287]: I HouseKeeping
housekeeper.cpp:244 (RunHouseKeeping) mythfilldatabase still running,
skipping checks.
Sep 23 14:24:49 stalker mythfilldatabase[31743]: I CoreContext
filldata.cpp:321 (GrabDDData) Program table update complete.
Sep 23 14:24:49 stalker mythfilldatabase[31743]: N CoreContext
main.cpp:488 (main) Data fetching complete.
Sep 23 14:24:49 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:527 (main) Adjusting program database end times.
Sep 23 14:24:50 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:533 (main) 0 replacements made
Sep 23 14:24:50 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:538 (main) Marking generic episodes.
Sep 23 14:24:51 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:550 (main) Found 27935
Sep 23 14:24:51 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:556 (main) Extending non-unique programids with multiple parts.
Sep 23 14:24:51 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:589 (main) SH014067210000 -> SH014067210201 (part 1 of 2)
Sep 23 14:24:51 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:607 (main) Found 1
Sep 23 14:24:51 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:612 (main) Marking repeats.
Sep 23 14:24:53 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:626 (main) Found 58296
Sep 23 14:24:53 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:628 (main) Unmarking new episode rebroadcast repeats.
Sep 23 14:24:53 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:638 (main) Found 0
Sep 23 14:24:55 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:650 (main) Marking episode first showings.
Sep 23 14:25:02 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:691 (main) Found 34676
Sep 23 14:25:02 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:693 (main) Marking episode last showings.
Sep 23 14:25:10 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:732 (main) Found 34676
Sep 23 14:25:10 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1061 (GrabNextSuggestedTime) DataDirect: Grabbing next
suggested grabbing time
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1099 (GrabNextSuggestedTime) Suggested Time data: 612 bytes
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1141 (GrabNextSuggestedTime) DataDirect: BlockedTime is:
2012-09-23T14:25:11
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:1128 (GrabNextSuggestedTime) DataDirect:
NextSuggestedTime is: 2012-09-24T14:52:03
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
main.cpp:760 (main)
#012===============================================================#012|
Attempting to contact the master backend for rescheduling. |#012| If
the master is not running, rescheduling will happen when |#012| the
master backend is restarted.
|#012===============================================================
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext:
Connecting to backend server: 10.1.10.150:6543 (try 1 of 1)
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Sep 23 14:25:11 stalker mythfilldatabase[31743]: N CoreContext
main.cpp:769 (main) mythfilldatabase run complete.
Sep 23 14:25:11 stalker mythfilldatabase[31743]: I CoreContext
datadirect.cpp:573 (~DataDirectProcessor) DataDirect: Deleting temporary
files
More information about the mythtv-users
mailing list